builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1474060689.22 results: success (0) buildid: 20160916123016 builduid: a2605c2e07f14617979229ff45e7fd90 revision: a76f19132914a2e8d17aa909162139d3a5916b05 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.217565) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.218086) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.218432) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.246641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.246966) ========= 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=cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 14:18:09-- 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% 42.7M=0s 2016-09-16 14:18:09 (42.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103899 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.373207) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.373545) ========= 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=cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020784 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.435962) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:09.436355) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a76f19132914a2e8d17aa909162139d3a5916b05 --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 a76f19132914a2e8d17aa909162139d3a5916b05 --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=cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 14:18:09,942 truncating revision to first 12 chars 2016-09-16 14:18:09,942 Setting DEBUG logging. 2016-09-16 14:18:09,942 attempt 1/10 2016-09-16 14:18:09,942 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a76f19132914?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-16 14:18:10,251 unpacking tar archive at: autoland-a76f19132914/testing/mozharness/ program finished with exit code 0 elapsedTime=0.641045 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:10.101509) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:10.101940) ========= script_repo_revision: a76f19132914a2e8d17aa909162139d3a5916b05 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:10.102456) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:10.102816) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 14:18:10.121242) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-09-16 14:18:10.121556) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:18:10 INFO - MultiFileLogger online at 20160916 14:18:10 in /builds/slave/test 14:18:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 14:18:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:18:10 INFO - {'append_to_log': False, 14:18:10 INFO - 'base_work_dir': '/builds/slave/test', 14:18:10 INFO - 'blob_upload_branch': 'Autoland', 14:18:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:18:10 INFO - 'branch': 'Autoland', 14:18:10 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:10 INFO - 'config_files': ('talos/linux_config.py',), 14:18:10 INFO - 'default_actions': ('clobber', 14:18:10 INFO - 'read-buildbot-config', 14:18:10 INFO - 'download-and-extract', 14:18:10 INFO - 'populate-webroot', 14:18:10 INFO - 'create-virtualenv', 14:18:10 INFO - 'install', 14:18:10 INFO - 'run-tests'), 14:18:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:18:10 INFO - 'download_minidump_stackwalk': True, 14:18:10 INFO - 'download_symbols': 'ondemand', 14:18:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:18:10 INFO - 'tooltool.py': '/tools/tooltool.py', 14:18:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:18:10 INFO - '/tools/misc-python/virtualenv.py')}, 14:18:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:18:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:18:10 INFO - 'installer_path': 'installer.exe', 14:18:10 INFO - 'log_level': 'info', 14:18:10 INFO - 'log_name': 'talos', 14:18:10 INFO - 'log_to_console': True, 14:18:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:18:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:18:10 INFO - 'opt_config_files': (), 14:18:10 INFO - 'pip_index': False, 14:18:10 INFO - 'sps_profile': False, 14:18:10 INFO - 'sps_profile_interval': 0, 14:18:10 INFO - 'suite': 'dromaeojs', 14:18:10 INFO - 'system_bits': '32', 14:18:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:18:10 INFO - 'title': 'talos-linux64-ix-054', 14:18:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:18:10 INFO - 'use_talos_json': True, 14:18:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:18:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:10 INFO - 'work_dir': 'build'} 14:18:10 INFO - [mozharness: 2016-09-16 21:18:10.715810Z] Running clobber step. 14:18:10 INFO - Running pre-action listener: _resource_record_pre_action 14:18:10 INFO - Running main action method: clobber 14:18:10 INFO - rmtree: /builds/slave/test/build 14:18:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:18:11 INFO - Running post-action listener: _resource_record_post_action 14:18:11 INFO - [mozharness: 2016-09-16 21:18:11.186260Z] Finished clobber step (success) 14:18:11 INFO - [mozharness: 2016-09-16 21:18:11.186360Z] Running read-buildbot-config step. 14:18:11 INFO - Running pre-action listener: _resource_record_pre_action 14:18:11 INFO - Running main action method: read_buildbot_config 14:18:11 INFO - Using buildbot properties: 14:18:11 INFO - { 14:18:11 INFO - "project": "", 14:18:11 INFO - "product": "firefox", 14:18:11 INFO - "script_repo_revision": "production", 14:18:11 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 14:18:11 INFO - "repository": "", 14:18:11 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 14:18:11 INFO - "buildid": "20160916123016", 14:18:11 INFO - "pgo_build": "True", 14:18:11 INFO - "basedir": "/builds/slave/test", 14:18:11 INFO - "buildnumber": 202, 14:18:11 INFO - "slavename": "talos-linux64-ix-054", 14:18:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 14:18:11 INFO - "platform": "ubuntu64_hw", 14:18:11 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 14:18:11 INFO - "branch": "autoland", 14:18:11 INFO - "repo_path": "integration/autoland", 14:18:11 INFO - "revision": "a76f19132914a2e8d17aa909162139d3a5916b05", 14:18:11 INFO - "stage_platform": "linux64-pgo", 14:18:11 INFO - "builduid": "a2605c2e07f14617979229ff45e7fd90", 14:18:11 INFO - "slavebuilddir": "test" 14:18:11 INFO - } 14:18:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 14:18:11 INFO - Running post-action listener: _resource_record_post_action 14:18:11 INFO - [mozharness: 2016-09-16 21:18:11.188812Z] Finished read-buildbot-config step (success) 14:18:11 INFO - [mozharness: 2016-09-16 21:18:11.188903Z] Running download-and-extract step. 14:18:11 INFO - Running pre-action listener: _resource_record_pre_action 14:18:11 INFO - Running main action method: download_and_extract 14:18:11 INFO - mkdir: /builds/slave/test/build/tests 14:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:11 INFO - trying https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:18:11 INFO - Downloading https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/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 14:18:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/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 14:18:12 INFO - Downloaded 1361 bytes. 14:18:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 14:18:12 INFO - Using the following test package requirements: 14:18:12 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:18:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:18:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:18:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:12 INFO - u'jsshell-linux-x86_64.zip'], 14:18:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:18:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 14:18:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:18:12 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:18:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:18:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:12 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:18:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:18:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:18:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:18:12 INFO - Fetch https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:18:13 INFO - Expected file size: 21936200 14:18:17 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 14:18:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 14:18:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:18:17 INFO - Fetch https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:18:18 INFO - Expected file size: 21936200 14:18:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 14:18:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:18:22 INFO - Fetch https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:18:23 INFO - Expected file size: 14414048 14:18:24 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')]} 14:18:24 INFO - trying https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:18:24 INFO - Downloading https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/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 14:18:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/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 14:18:26 INFO - Downloaded 59571328 bytes. 14:18:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:18:26 INFO - mkdir: /builds/slave/test/properties 14:18:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:18:26 INFO - Writing to file /builds/slave/test/properties/build_url 14:18:26 INFO - Contents: 14:18:26 INFO - build_url:https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 14:18:27 INFO - Running post-action listener: _resource_record_post_action 14:18:27 INFO - Running post-action listener: set_extra_try_arguments 14:18:27 INFO - [mozharness: 2016-09-16 21:18:27.627576Z] Finished download-and-extract step (success) 14:18:27 INFO - [mozharness: 2016-09-16 21:18:27.627808Z] Running populate-webroot step. 14:18:27 INFO - Running pre-action listener: _resource_record_pre_action 14:18:27 INFO - Running main action method: populate_webroot 14:18:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:18:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:18:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:18:27 INFO - '--tppagecycles', 14:18:27 INFO - '7'], 14:18:27 INFO - 'tests': ['tsvgm']}}, 14:18:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:18:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:18:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:18:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:18:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:27 INFO - 'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:27 INFO - 'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['damp', 'tps']}, 14:18:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:27 INFO - 'tests': ['damp', 'tps']}, 14:18:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['dromaeo_dom']}, 14:18:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:18:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['basic_compositor_video']}, 14:18:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:18:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other-e10s': {'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['a11yr', 14:18:27 INFO - 'ts_paint', 14:18:27 INFO - 'tpaint', 14:18:27 INFO - 'sessionrestore', 14:18:27 INFO - 'sessionrestore_no_auto_restore', 14:18:27 INFO - 'tabpaint']}, 14:18:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['tsvgx', 14:18:27 INFO - 'tsvgr_opacity', 14:18:27 INFO - 'tart', 14:18:27 INFO - 'tscrollx', 14:18:27 INFO - 'cart']}, 14:18:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:18:27 INFO - 'tsvgr_opacity', 14:18:27 INFO - 'tart', 14:18:27 INFO - 'tscrollx', 14:18:27 INFO - 'cart']}, 14:18:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:27 INFO - 'talos_options': ['--disable-e10s'], 14:18:27 INFO - 'tests': ['tp5o']}, 14:18:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:27 INFO - 'tests': ['tp5o']}, 14:18:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:27 INFO - 'talos_options': ['--disable-e10s', 14:18:27 INFO - '--xperf_path', 14:18:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:27 INFO - 'tests': ['tp5n']}, 14:18:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:27 INFO - 'talos_options': ['--xperf_path', 14:18:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:27 INFO - 'tests': ['tp5n']}}, 14:18:27 INFO - 'talos.zip': {'path': '', 14:18:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:18:27 INFO - Running post-action listener: _resource_record_post_action 14:18:27 INFO - [mozharness: 2016-09-16 21:18:27.646086Z] Finished populate-webroot step (success) 14:18:27 INFO - [mozharness: 2016-09-16 21:18:27.646175Z] Running create-virtualenv step. 14:18:27 INFO - Running pre-action listener: _resource_record_pre_action 14:18:27 INFO - Running main action method: create_virtualenv 14:18:27 INFO - Creating virtualenv /builds/slave/test/build/venv 14:18:27 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 14:18:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:18:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:18:27 INFO - Using real prefix '/usr' 14:18:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:18:28 INFO - Installing distribute.............................................................................................................................................................................................done. 14:18:30 INFO - Installing pip.................done. 14:18:30 INFO - Return code: 0 14:18:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:18:30 INFO - Using env: {'DISPLAY': ':0', 14:18:30 INFO - 'HOME': '/home/cltbld', 14:18:30 INFO - 'LANG': 'en_US.UTF-8', 14:18:30 INFO - 'LANGUAGE': 'en_US:en', 14:18:30 INFO - 'LOGNAME': 'cltbld', 14:18:30 INFO - 'MAIL': '/var/mail/cltbld', 14:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:30 INFO - 'MOZ_NO_REMOTE': '1', 14:18:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:30 INFO - 'NO_EM_RESTART': '1', 14:18:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:30 INFO - 'PWD': '/builds/slave/test', 14:18:30 INFO - 'SHELL': '/bin/bash', 14:18:30 INFO - 'SHLVL': '1', 14:18:30 INFO - 'TERM': 'linux', 14:18:30 INFO - 'TMOUT': '86400', 14:18:30 INFO - 'USER': 'cltbld', 14:18:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:30 INFO - '_': '/tools/buildbot/bin/python'} 14:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:30 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 14:18:30 INFO - Cleaning up... 14:18:30 INFO - Return code: 0 14:18:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:18:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:18:30 INFO - Using env: {'DISPLAY': ':0', 14:18:30 INFO - 'HOME': '/home/cltbld', 14:18:30 INFO - 'LANG': 'en_US.UTF-8', 14:18:30 INFO - 'LANGUAGE': 'en_US:en', 14:18:30 INFO - 'LOGNAME': 'cltbld', 14:18:30 INFO - 'MAIL': '/var/mail/cltbld', 14:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:30 INFO - 'MOZ_NO_REMOTE': '1', 14:18:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:30 INFO - 'NO_EM_RESTART': '1', 14:18:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:30 INFO - 'PWD': '/builds/slave/test', 14:18:30 INFO - 'SHELL': '/bin/bash', 14:18:30 INFO - 'SHLVL': '1', 14:18:30 INFO - 'TERM': 'linux', 14:18:30 INFO - 'TMOUT': '86400', 14:18:30 INFO - 'USER': 'cltbld', 14:18:30 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:30 INFO - '_': '/tools/buildbot/bin/python'} 14:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:30 INFO - Downloading/unpacking psutil>=3.1.1 14:18:30 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 14:18:30 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 14:18:30 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 14:18:30 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 14:18:30 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 14:18:30 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 14:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:18:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:32 INFO - Installing collected packages: psutil 14:18:32 INFO - Running setup.py install for psutil 14:18:32 INFO - building 'psutil._psutil_linux' extension 14:18:32 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 14:18:32 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 14:18:32 INFO - building 'psutil._psutil_posix' extension 14:18:32 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 14:18:33 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 14:18:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:33 INFO - Successfully installed psutil 14:18:33 INFO - Cleaning up... 14:18:33 INFO - Return code: 0 14:18:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:18: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')]} 14:18:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18: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')]} 14:18:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:33 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 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:33 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 14:18:33 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 14:18:33 INFO - Using env: {'DISPLAY': ':0', 14:18:33 INFO - 'HOME': '/home/cltbld', 14:18:33 INFO - 'LANG': 'en_US.UTF-8', 14:18:33 INFO - 'LANGUAGE': 'en_US:en', 14:18:33 INFO - 'LOGNAME': 'cltbld', 14:18:33 INFO - 'MAIL': '/var/mail/cltbld', 14:18:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:33 INFO - 'MOZ_NO_REMOTE': '1', 14:18:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:33 INFO - 'NO_EM_RESTART': '1', 14:18:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:33 INFO - 'PWD': '/builds/slave/test', 14:18:33 INFO - 'SHELL': '/bin/bash', 14:18:33 INFO - 'SHLVL': '1', 14:18:33 INFO - 'TERM': 'linux', 14:18:33 INFO - 'TMOUT': '86400', 14:18:33 INFO - 'USER': 'cltbld', 14:18:33 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:33 INFO - '_': '/tools/buildbot/bin/python'} 14:18:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:18:33 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 14:18:33 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 14:18:33 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 14:18:33 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 14:18:33 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 14:18:33 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 14:18:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:18:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:18:35 INFO - Installing collected packages: mozsystemmonitor 14:18:35 INFO - Running setup.py install for mozsystemmonitor 14:18:35 INFO - Successfully installed mozsystemmonitor 14:18:35 INFO - Cleaning up... 14:18:35 INFO - Return code: 0 14:18:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:35 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 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:35 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 14:18:35 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 14:18:35 INFO - Using env: {'DISPLAY': ':0', 14:18:35 INFO - 'HOME': '/home/cltbld', 14:18:35 INFO - 'LANG': 'en_US.UTF-8', 14:18:35 INFO - 'LANGUAGE': 'en_US:en', 14:18:35 INFO - 'LOGNAME': 'cltbld', 14:18:35 INFO - 'MAIL': '/var/mail/cltbld', 14:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:35 INFO - 'MOZ_NO_REMOTE': '1', 14:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:35 INFO - 'NO_EM_RESTART': '1', 14:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:35 INFO - 'PWD': '/builds/slave/test', 14:18:35 INFO - 'SHELL': '/bin/bash', 14:18:35 INFO - 'SHLVL': '1', 14:18:35 INFO - 'TERM': 'linux', 14:18:35 INFO - 'TMOUT': '86400', 14:18:35 INFO - 'USER': 'cltbld', 14:18:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:35 INFO - '_': '/tools/buildbot/bin/python'} 14:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:35 INFO - Downloading/unpacking jsonschema==2.5.1 14:18:35 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 14:18:35 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 14:18:35 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 14:18:35 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 14:18:35 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 14:18:35 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 14:18:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:18:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:18:37 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 14:18:37 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 14:18:37 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 14:18:37 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 14:18:37 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 14:18:37 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 14:18:37 INFO - Downloading functools32-3.2.3-2.tar.gz 14:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:18:37 INFO - warning: no files found matching '*.txt' 14:18:37 INFO - no previously-included directories found matching 'build' 14:18:37 INFO - no previously-included directories found matching 'dist' 14:18:37 INFO - no previously-included directories found matching '.git*' 14:18:37 INFO - Installing collected packages: jsonschema, functools32 14:18:37 INFO - Running setup.py install for functools32 14:18:37 INFO - warning: no files found matching '*.txt' 14:18:37 INFO - no previously-included directories found matching 'build' 14:18:37 INFO - no previously-included directories found matching 'dist' 14:18:37 INFO - no previously-included directories found matching '.git*' 14:18:37 INFO - Successfully installed jsonschema functools32 14:18:37 INFO - Cleaning up... 14:18:38 INFO - Return code: 0 14:18:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:18: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')]} 14:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18: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')]} 14:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:38 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 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:38 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 14:18:38 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 14:18:38 INFO - Using env: {'DISPLAY': ':0', 14:18:38 INFO - 'HOME': '/home/cltbld', 14:18:38 INFO - 'LANG': 'en_US.UTF-8', 14:18:38 INFO - 'LANGUAGE': 'en_US:en', 14:18:38 INFO - 'LOGNAME': 'cltbld', 14:18:38 INFO - 'MAIL': '/var/mail/cltbld', 14:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:38 INFO - 'MOZ_NO_REMOTE': '1', 14:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:38 INFO - 'NO_EM_RESTART': '1', 14:18:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:38 INFO - 'PWD': '/builds/slave/test', 14:18:38 INFO - 'SHELL': '/bin/bash', 14:18:38 INFO - 'SHLVL': '1', 14:18:38 INFO - 'TERM': 'linux', 14:18:38 INFO - 'TMOUT': '86400', 14:18:38 INFO - 'USER': 'cltbld', 14:18:38 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:38 INFO - '_': '/tools/buildbot/bin/python'} 14:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:18:38 INFO - Cleaning up... 14:18:38 INFO - Return code: 0 14:18:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:18: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')]} 14:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18: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')]} 14:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:38 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 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:38 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 14:18:38 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 14:18:38 INFO - Using env: {'DISPLAY': ':0', 14:18:38 INFO - 'HOME': '/home/cltbld', 14:18:38 INFO - 'LANG': 'en_US.UTF-8', 14:18:38 INFO - 'LANGUAGE': 'en_US:en', 14:18:38 INFO - 'LOGNAME': 'cltbld', 14:18:38 INFO - 'MAIL': '/var/mail/cltbld', 14:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:38 INFO - 'MOZ_NO_REMOTE': '1', 14:18:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:38 INFO - 'NO_EM_RESTART': '1', 14:18:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:38 INFO - 'PWD': '/builds/slave/test', 14:18:38 INFO - 'SHELL': '/bin/bash', 14:18:38 INFO - 'SHLVL': '1', 14:18:38 INFO - 'TERM': 'linux', 14:18:38 INFO - 'TMOUT': '86400', 14:18:38 INFO - 'USER': 'cltbld', 14:18:38 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:38 INFO - '_': '/tools/buildbot/bin/python'} 14:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:38 INFO - Downloading/unpacking blobuploader==1.2.4 14:18:38 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 14:18:38 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 14:18:38 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 14:18:38 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 14:18:38 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 14:18:38 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 14:18:40 INFO - Downloading blobuploader-1.2.4.tar.gz 14:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:18:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:18:40 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 14:18:40 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 14:18:40 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 14:18:40 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 14:18:40 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 14:18:40 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 14:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:18:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:18:40 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 14:18:40 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 14:18:40 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 14:18:40 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 14:18:40 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 14:18:40 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 14:18:41 INFO - Downloading docopt-0.6.1.tar.gz 14:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:18:41 INFO - Installing collected packages: blobuploader, requests, docopt 14:18:41 INFO - Running setup.py install for blobuploader 14:18:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:18:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:18:41 INFO - Running setup.py install for requests 14:18:41 INFO - Running setup.py install for docopt 14:18:41 INFO - Successfully installed blobuploader requests docopt 14:18:41 INFO - Cleaning up... 14:18:41 INFO - Return code: 0 14:18:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:41 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')]} 14:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:41 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')]} 14:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:41 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 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:41 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 14:18:41 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 14:18:41 INFO - Using env: {'DISPLAY': ':0', 14:18:41 INFO - 'HOME': '/home/cltbld', 14:18:41 INFO - 'LANG': 'en_US.UTF-8', 14:18:41 INFO - 'LANGUAGE': 'en_US:en', 14:18:41 INFO - 'LOGNAME': 'cltbld', 14:18:41 INFO - 'MAIL': '/var/mail/cltbld', 14:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:41 INFO - 'MOZ_NO_REMOTE': '1', 14:18:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:41 INFO - 'NO_EM_RESTART': '1', 14:18:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:41 INFO - 'PWD': '/builds/slave/test', 14:18:41 INFO - 'SHELL': '/bin/bash', 14:18:41 INFO - 'SHLVL': '1', 14:18:41 INFO - 'TERM': 'linux', 14:18:41 INFO - 'TMOUT': '86400', 14:18:41 INFO - 'USER': 'cltbld', 14:18:41 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:41 INFO - '_': '/tools/buildbot/bin/python'} 14:18:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:41 INFO - Running setup.py (path:/tmp/pip-A11JZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:42 INFO - Running setup.py (path:/tmp/pip-n1Rurf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:42 INFO - Running setup.py (path:/tmp/pip-yY6DMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:42 INFO - Running setup.py (path:/tmp/pip-yfY7Kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:42 INFO - Running setup.py (path:/tmp/pip-5TvW_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:42 INFO - Running setup.py (path:/tmp/pip-lhmrmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:42 INFO - Running setup.py (path:/tmp/pip-3W2nNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:42 INFO - Running setup.py (path:/tmp/pip-bXvkkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:42 INFO - Running setup.py (path:/tmp/pip-MWNFBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:42 INFO - Running setup.py (path:/tmp/pip-j_jihS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:42 INFO - Running setup.py (path:/tmp/pip-uyMpQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:42 INFO - Running setup.py (path:/tmp/pip-ZnfDG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:42 INFO - Running setup.py (path:/tmp/pip-vZgrPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:42 INFO - Running setup.py (path:/tmp/pip-Tnj0Fq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:42 INFO - Running setup.py (path:/tmp/pip-g5HkTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:43 INFO - Running setup.py (path:/tmp/pip-oxkP3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:43 INFO - Running setup.py (path:/tmp/pip-axYsy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:18:43 INFO - Running setup.py install for manifestparser 14:18:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Running setup.py install for mozcrash 14:18:43 INFO - Running setup.py install for mozdebug 14:18:43 INFO - Running setup.py install for mozdevice 14:18:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Running setup.py install for mozfile 14:18:43 INFO - Running setup.py install for mozhttpd 14:18:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Running setup.py install for mozinfo 14:18:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Running setup.py install for mozInstall 14:18:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:18:43 INFO - Running setup.py install for mozleak 14:18:44 INFO - Running setup.py install for mozlog 14:18:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:18:44 INFO - Running setup.py install for moznetwork 14:18:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:18:44 INFO - Running setup.py install for mozprocess 14:18:44 INFO - Running setup.py install for mozprofile 14:18:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:18:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:18:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:18:44 INFO - Running setup.py install for mozrunner 14:18:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:18:44 INFO - Running setup.py install for mozscreenshot 14:18:44 INFO - Running setup.py install for moztest 14:18:44 INFO - Running setup.py install for mozversion 14:18:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:18:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:18:44 INFO - Cleaning up... 14:18:44 INFO - Return code: 0 14:18:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:45 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 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:45 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 14:18:45 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 14:18:45 INFO - Using env: {'DISPLAY': ':0', 14:18:45 INFO - 'HOME': '/home/cltbld', 14:18:45 INFO - 'LANG': 'en_US.UTF-8', 14:18:45 INFO - 'LANGUAGE': 'en_US:en', 14:18:45 INFO - 'LOGNAME': 'cltbld', 14:18:45 INFO - 'MAIL': '/var/mail/cltbld', 14:18:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:45 INFO - 'MOZ_NO_REMOTE': '1', 14:18:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:45 INFO - 'NO_EM_RESTART': '1', 14:18:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:45 INFO - 'PWD': '/builds/slave/test', 14:18:45 INFO - 'SHELL': '/bin/bash', 14:18:45 INFO - 'SHLVL': '1', 14:18:45 INFO - 'TERM': 'linux', 14:18:45 INFO - 'TMOUT': '86400', 14:18:45 INFO - 'USER': 'cltbld', 14:18:45 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:45 INFO - '_': '/tools/buildbot/bin/python'} 14:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:45 INFO - Running setup.py (path:/tmp/pip-LRfGRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:45 INFO - Running setup.py (path:/tmp/pip-Ndnzxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:45 INFO - Running setup.py (path:/tmp/pip-MDsXDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:45 INFO - Running setup.py (path:/tmp/pip-Miztc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:45 INFO - Running setup.py (path:/tmp/pip-MseTxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:45 INFO - Running setup.py (path:/tmp/pip-xMfvVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:45 INFO - Running setup.py (path:/tmp/pip-alAIIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:45 INFO - Running setup.py (path:/tmp/pip-Jzjc4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:45 INFO - Running setup.py (path:/tmp/pip-2rG2lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:45 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)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:45 INFO - Running setup.py (path:/tmp/pip-V4MSpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:18:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:45 INFO - Running setup.py (path:/tmp/pip-ZYQMMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:46 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)) 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:46 INFO - Running setup.py (path:/tmp/pip-4pDylg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:46 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)) 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:46 INFO - Running setup.py (path:/tmp/pip-AAWGGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:46 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)) 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:46 INFO - Running setup.py (path:/tmp/pip-Ere1nR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:46 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)) 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:46 INFO - Running setup.py (path:/tmp/pip-Z80lOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:46 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)) 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:46 INFO - Running setup.py (path:/tmp/pip-C9MZMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:46 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)) 14:18:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:46 INFO - Running setup.py (path:/tmp/pip-eVoqqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:46 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)) 14:18:46 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)) 14:18:46 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)) 14:18:46 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)) 14:18:46 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)) 14:18:46 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)) 14:18:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:18:46 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 14:18:46 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 14:18:46 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 14:18:46 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 14:18:46 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 14:18:46 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 14:18:48 INFO - Downloading blessings-1.6.tar.gz 14:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:18:48 INFO - Installing collected packages: blessings 14:18:48 INFO - Running setup.py install for blessings 14:18:48 INFO - Successfully installed blessings 14:18:48 INFO - Cleaning up... 14:18:48 INFO - Return code: 0 14:18:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:18:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:48 INFO - Reading from file tmpfile_stdout 14:18:48 INFO - Current package versions: 14:18:48 INFO - argparse == 1.2.1 14:18:48 INFO - blessings == 1.6 14:18:48 INFO - blobuploader == 1.2.4 14:18:48 INFO - docopt == 0.6.1 14:18:48 INFO - functools32 == 3.2.3-2 14:18:48 INFO - jsonschema == 2.5.1 14:18:48 INFO - manifestparser == 1.1 14:18:48 INFO - mozInstall == 1.12 14:18:48 INFO - mozcrash == 0.17 14:18:48 INFO - mozdebug == 0.1 14:18:48 INFO - mozdevice == 0.48 14:18:48 INFO - mozfile == 1.2 14:18:48 INFO - mozhttpd == 0.7 14:18:48 INFO - mozinfo == 0.9 14:18:48 INFO - mozleak == 0.1 14:18:48 INFO - mozlog == 3.4 14:18:48 INFO - moznetwork == 0.27 14:18:48 INFO - mozprocess == 0.23 14:18:48 INFO - mozprofile == 0.28 14:18:48 INFO - mozrunner == 6.12 14:18:48 INFO - mozscreenshot == 0.1 14:18:48 INFO - mozsystemmonitor == 0.3 14:18:48 INFO - moztest == 0.7 14:18:48 INFO - mozversion == 1.4 14:18:48 INFO - psutil == 3.1.1 14:18:48 INFO - requests == 1.2.3 14:18:48 INFO - wsgiref == 0.1.2 14:18:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:48 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 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:48 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 14:18:48 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 14:18:48 INFO - Using env: {'DISPLAY': ':0', 14:18:48 INFO - 'HOME': '/home/cltbld', 14:18:48 INFO - 'LANG': 'en_US.UTF-8', 14:18:48 INFO - 'LANGUAGE': 'en_US:en', 14:18:48 INFO - 'LOGNAME': 'cltbld', 14:18:48 INFO - 'MAIL': '/var/mail/cltbld', 14:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:48 INFO - 'MOZ_NO_REMOTE': '1', 14:18:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:48 INFO - 'NO_EM_RESTART': '1', 14:18:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:48 INFO - 'PWD': '/builds/slave/test', 14:18:48 INFO - 'SHELL': '/bin/bash', 14:18:48 INFO - 'SHLVL': '1', 14:18:48 INFO - 'TERM': 'linux', 14:18:48 INFO - 'TMOUT': '86400', 14:18:48 INFO - 'USER': 'cltbld', 14:18:48 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:48 INFO - '_': '/tools/buildbot/bin/python'} 14:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 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)) 14:18:49 INFO - Cleaning up... 14:18:49 INFO - Return code: 0 14:18:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f039bb03f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a6790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, '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': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', '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 14:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:18:49 INFO - Using env: {'DISPLAY': ':0', 14:18:49 INFO - 'HOME': '/home/cltbld', 14:18:49 INFO - 'LANG': 'en_US.UTF-8', 14:18:49 INFO - 'LANGUAGE': 'en_US:en', 14:18:49 INFO - 'LOGNAME': 'cltbld', 14:18:49 INFO - 'MAIL': '/var/mail/cltbld', 14:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:49 INFO - 'MOZ_NO_REMOTE': '1', 14:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:49 INFO - 'NO_EM_RESTART': '1', 14:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:49 INFO - 'PWD': '/builds/slave/test', 14:18:49 INFO - 'SHELL': '/bin/bash', 14:18:49 INFO - 'SHLVL': '1', 14:18:49 INFO - 'TERM': 'linux', 14:18:49 INFO - 'TMOUT': '86400', 14:18:49 INFO - 'USER': 'cltbld', 14:18:49 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:49 INFO - '_': '/tools/buildbot/bin/python'} 14:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:18:49 INFO - Cleaning up... 14:18:49 INFO - Return code: 0 14:18:49 INFO - Running post-action listener: _resource_record_post_action 14:18:49 INFO - Running post-action listener: _start_resource_monitoring 14:18:49 INFO - Starting resource monitoring. 14:18:49 INFO - [mozharness: 2016-09-16 21:18:49.289181Z] Finished create-virtualenv step (success) 14:18:49 INFO - [mozharness: 2016-09-16 21:18:49.289689Z] Running install step. 14:18:49 INFO - Running pre-action listener: _resource_record_pre_action 14:18:49 INFO - Running main action method: install 14:18:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:49 INFO - Reading from file tmpfile_stdout 14:18:49 INFO - Detecting whether we're running mozinstall >=1.0... 14:18:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:18:49 INFO - Reading from file tmpfile_stdout 14:18:49 INFO - Output received: 14:18:49 INFO - Usage: mozinstall [options] installer 14:18:49 INFO - Options: 14:18:49 INFO - -h, --help show this help message and exit 14:18:49 INFO - -d DEST, --destination=DEST 14:18:49 INFO - Directory to install application into. [default: 14:18:49 INFO - "/builds/slave/test"] 14:18:49 INFO - --app=APP Application being installed. [default: firefox] 14:18:49 INFO - mkdir: /builds/slave/test/build/application 14:18:49 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'] 14:18:49 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 14:19:07 INFO - Reading from file tmpfile_stdout 14:19:07 INFO - Output received: 14:19:07 INFO - /builds/slave/test/build/application/firefox/firefox 14:19:07 INFO - Running post-action listener: _resource_record_post_action 14:19:07 INFO - [mozharness: 2016-09-16 21:19:07.079395Z] Finished install step (success) 14:19:07 INFO - [mozharness: 2016-09-16 21:19:07.079816Z] Running run-tests step. 14:19:07 INFO - Running pre-action listener: _resource_record_pre_action 14:19:07 INFO - Running main action method: run_tests 14:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:07 INFO - Python 2.7.3 14:19:07 INFO - Return code: 0 14:19:07 INFO - grabbing minidump binary from tooltool 14:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:07 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 0x18a3930>, '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 0x18a4830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a4cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:07 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 14:19:07 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 14:19:07 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 14:19:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:07 INFO - Return code: 0 14:19:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:19:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:19:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 14:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --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 14:19:07 INFO - Using env: {'DISPLAY': ':0', 14:19:07 INFO - 'HOME': '/home/cltbld', 14:19:07 INFO - 'LANG': 'en_US.UTF-8', 14:19:07 INFO - 'LANGUAGE': 'en_US:en', 14:19:07 INFO - 'LOGNAME': 'cltbld', 14:19:07 INFO - 'MAIL': '/var/mail/cltbld', 14:19:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:07 INFO - 'MOZ_NO_REMOTE': '1', 14:19:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:07 INFO - 'NO_EM_RESTART': '1', 14:19:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:07 INFO - 'PWD': '/builds/slave/test', 14:19:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:07 INFO - 'SHELL': '/bin/bash', 14:19:07 INFO - 'SHLVL': '1', 14:19:07 INFO - 'TERM': 'linux', 14:19:07 INFO - 'TMOUT': '86400', 14:19:07 INFO - 'USER': 'cltbld', 14:19:07 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508', 14:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:07 INFO - '_': '/tools/buildbot/bin/python'} 14:19:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--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 14:19:07 INFO - mozversion application_buildid: 20160916123016 14:19:07 INFO - mozversion application_changeset: a76f19132914a2e8d17aa909162139d3a5916b05 14:19:07 INFO - mozversion application_display_name: Nightly 14:19:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:07 INFO - mozversion application_name: Firefox 14:19:07 INFO - mozversion application_remotingname: firefox 14:19:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:19:07 INFO - mozversion application_vendor: Mozilla 14:19:07 INFO - mozversion application_version: 51.0a1 14:19:07 INFO - mozversion platform_buildid: 20160916123016 14:19:07 INFO - mozversion platform_changeset: a76f19132914a2e8d17aa909162139d3a5916b05 14:19:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:19:07 INFO - mozversion platform_version: 51.0a1 14:19:07 INFO - using testdate: 1474060747 14:19:07 INFO - actual date: 1474060747 14:19:07 INFO - starting webserver on 'localhost:44486' 14:19:07 INFO - SUITE-START | Running 2 tests 14:19:07 INFO - TEST-START | dromaeo_css 14:19:07 INFO - operating with platform_type : linux_ 14:19:07 INFO - Initialising browser for dromaeo_css test... 14:19:07 INFO - TEST-INFO | started process 9348 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCm66dz/profile http://localhost:44486/getInfo.html) 14:19:11 INFO - PROCESS | 9348 | __metrics Screen width/height:1600/1200 14:19:11 INFO - PROCESS | 9348 | colorDepth:24 14:19:11 INFO - PROCESS | 9348 | Browser inner width/height: 1024/697 14:19:11 INFO - PROCESS | 9348 | __metrics 14:19:12 INFO - PROCESS | 9348 | 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" 14:19:12 INFO - PROCESS | 9348 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:19:12 INFO - PROCESS | 9348 | console.error: PushService: 14:19:12 INFO - PROCESS | 9348 | stateChangeProcessEnqueue: Error transitioning state 14:19:12 INFO - PROCESS | 9348 | UnknownError 14:19:13 INFO - TEST-INFO | 9348: exit 0 14:19:13 INFO - Browser initialized. 14:19:13 INFO - Running cycle 1/1 for dromaeo_css test... 14:19:13 INFO - TEST-INFO | started process 9431 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCm66dz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:25 INFO - PROCESS | 9431 | RSS: Main: 172679168 14:19:25 INFO - PROCESS | 9431 | 14:19:35 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:19:42 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:19:44 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:20:14 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.08338 seconds. 14:20:16 INFO - PROCESS | 9431 | 1474060816934 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] 14:20:17 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 14:20:31 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.16673 seconds. 14:20:35 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 14:21:00 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:21:03 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:21:05 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.16673 seconds. 14:21:13 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:21:17 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:21:28 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:21:37 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:21:40 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.15003 seconds. 14:21:42 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.63342 seconds. 14:21:45 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 14:21:52 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:22:02 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:22:04 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:22:09 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:22:10 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:22:26 INFO - PROCESS | 9431 | Cycle 1(1): loaded http://localhost:44486/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44486/tests/dromaeo/cssquery-ext.html) 14:22:27 INFO - PROCESS | 9431 | RSS: Main: 319586304 14:22:27 INFO - PROCESS | 9431 | 14:22:31 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 14:22:40 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:22:48 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:23:02 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:23:04 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:23:30 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 14:23:39 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:23:47 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:23:53 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:24:03 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:24:09 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:24:12 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 14:24:15 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:24:17 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:24:19 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:24:23 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:24:29 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:24:34 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 14:24:53 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:25:08 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:25:12 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:25:20 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:25:26 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:25:30 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 14:25:30 INFO - PROCESS | 9431 | Cycle 1(1): loaded http://localhost:44486/tests/dromaeo/cssquery-ext.html (next: http://localhost:44486/tests/dromaeo/cssquery-jquery.html) 14:25:31 INFO - PROCESS | 9431 | RSS: Main: 272072704 14:25:31 INFO - PROCESS | 9431 | 14:25:34 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.03345 seconds. 14:25:49 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:25:53 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:25:59 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:26:03 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:26:11 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:26:21 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:26:25 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:26:27 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:26:48 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:27:06 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:27:14 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:27:16 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:27:18 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:27:26 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:27:28 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:27:46 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 14:27:48 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:27:56 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:27:58 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:28:06 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:28:08 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:28:10 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:28:12 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:28:14 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:28:17 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:28:20 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:28:23 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:28:31 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:28:33 INFO - PROCESS | 9431 | Cycle 1(1): loaded http://localhost:44486/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44486/tests/dromaeo/cssquery-mootools.html) 14:28:33 INFO - PROCESS | 9431 | RSS: Main: 257593344 14:28:33 INFO - PROCESS | 9431 | 14:28:37 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:28:42 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:28:52 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 14:29:02 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:29:04 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:29:06 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:29:10 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:29:17 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 14:29:18 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:29:26 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:29:44 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:29:54 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:30:13 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:30:25 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:30:27 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:30:44 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:30:57 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:31:07 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:31:12 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:31:15 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:31:17 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:31:19 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:31:23 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:31:31 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:31:33 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:31:35 INFO - PROCESS | 9431 | Cycle 1(1): loaded http://localhost:44486/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44486/tests/dromaeo/cssquery-prototype.html) 14:31:36 INFO - PROCESS | 9431 | RSS: Main: 251142144 14:31:36 INFO - PROCESS | 9431 | 14:31:39 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 14:32:01 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:32:03 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:32:05 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 14:32:07 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:32:23 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:32:25 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:32:31 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:32:35 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:32:38 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:32:51 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:32:55 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:33:01 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:33:08 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 14:33:21 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 14:33:33 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01657 seconds. 14:33:44 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 14:33:46 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:34:06 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01659 seconds. 14:34:08 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 14:34:13 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 14:34:16 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:34:18 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 14:34:33 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:34:39 INFO - PROCESS | 9431 | Cycle 1(1): loaded http://localhost:44486/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44486/tests/dromaeo/cssquery-yui.html) 14:34:39 INFO - PROCESS | 9431 | RSS: Main: 220631040 14:34:39 INFO - PROCESS | 9431 | 14:34:43 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 14:34:44 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:34:47 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:34:56 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:35:24 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 14:35:28 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:35:34 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:35:47 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:35:58 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:35:59 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 14:36:02 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:36:18 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 14:36:25 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:36:35 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 14:36:41 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:36:43 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:36:49 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 14:37:09 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 14:37:36 INFO - PROCESS | 9431 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 14:37:42 INFO - PROCESS | 9431 | Cycle 1(1): loaded http://localhost:44486/tests/dromaeo/cssquery-yui.html (next: http://localhost:44486/tests/dromaeo/cssquery-dojo.html) 14:37:42 INFO - PROCESS | 9431 | RSS: Main: 299675648 14:37:42 INFO - PROCESS | 9431 | 14:37:42 INFO - PROCESS | 9431 | __start_tp_report 14:37:42 INFO - PROCESS | 9431 | _x_x_mozilla_page_load 14:37:42 INFO - PROCESS | 9431 | _x_x_mozilla_page_load_details 14:37:42 INFO - PROCESS | 9431 | |i|pagename|runs| 14:37:42 INFO - PROCESS | 9431 | |0;dojo.html;3008.0825348268777;3030.287882347648;3034.711702388274;3066.264096616813;3090.9072727818175;5095.235714642796;5160.200168973805;5221.06020916235;5221.295125158103;5225.503577160169;5153.948460515394;5182.8704282392955;5200.869978250536;5234.79060837567;5235.162374020158;4670.883227919295;4738.407699037621;4748.976255118737;4752.548507891762;4832.951670483303;4860.1494738420815;4885.658003939726;4889.26661000851;4901.632377571678;4931.876703082416;4622.976885115553;4672.6261899047995;4735.621150307967;4746.691465054795;4770.356001939728;4796.25658023007;4809.398825146856;4844.66087373884;4857.757112144379;4879.585235255007;4707.411573553306;4736.73947932867;4752.881177970544;4766.904661906777;4806.927896081562;5049.141645920203;5087.414947281073;5231.633785635007;5232.555232805216;5284.524392804666;5504;5560.388357280696;5561.082421400464;5574.637648552831;5652.858678533028;5590.720463976825;5599.804006859781;5611.158326251054;5611.915821262684;5613.158026296048;5242.606804489678;5244.082285600043;5250.396204436539;5253.7635806388425;5314.070037743418;5518;5570.860728481739;5657.547396208293;5685.687287199244;5707.686077265707;5439.945600543944;5493.7802487900035;5495;5495.697736624444;5551.944480555143;5520.861978450491;5563.582731295169;5573.721313934287;5585.357683866408;5586.888262234814;5390.838274851761;5433.701146436905;5462.289902312674;5491.039068163055;5634.464725851038;5537.169424586345;5543.501084902378;5596.496315331558;5604.075327570987;5609.663420194802;4308.353746937984;4311.676624253194;4313.61177494027;4317.11499142675;4371.409859668967;3808.4287356896134;3810.6951443884423;3823.5794062653085;3832.3868181090893;3900.551436584829;5271.551918086929;5283.52448279649;5285;5345.438728933484;5355.571554275649;5552.333719953551;5588.832335029955;5596.524295434852;5600.523955463831;5601.355844077902;5094.923576146361;5168.638195326366;5295.788168473295;5306.416294207634;5375.650582712111;4709.411323584552;4798.880027999258;4866.635002374836;4869.245266983623;4905.9264111038365;5846.327672317738;5856.765729370777;5858.414158584278;5860.38465961068;5865.061590145556;5649.802256920988;5654.264945557051;5657.745401456862;5662.462066103713;5681.0910254359105;5569.721513924369;5615.634983726045;5652.463016013472;5718.027935251101;5727.455891690282;5418.349798024262;5456.972715136399;5466.72666366688;5473.890522189614;5537.224788529528;5564.276644036249;5568.415316391721;5570.61005729595;5572.888542229214;5587.916181257203;5295.179247216687;5347.438518955453;5385.246065550904;5427.945720542744;5455.481729235717;5420.53925416344;5421.403645598902;5428.864278392914;5475.4250803665045;5486.451354864482;5420.647657902224;5425.538829199562;5426.593005524681;5428.782848686008;5488.396276409512;5251.107311756934;5313.442088580798;5381.434949330264;5439.238506609157;5450.645708028966;5284.7886084556185;5326.707031113326;5338.172583249602;5385.3268341457315;5408.161734931092 14:37:42 INFO - PROCESS | 9431 | |1;ext.html;573.6586730895118;573.7131434282858;583.2417856786177;585.4058130997038;596.7076132694981;32004.199895002606;42649.78675106624;43926.7803660982;44060.559394406046;46949.29576056362;44459.333110003376;44805.55194448055;44948.55051449485;44957;44987.550124498746;8880.777980550489;9037.096290370942;9041.3671043027;9041.728748137566;9049.095090490931;8882.777930551723;8979.14698103679;8981.191692747652;8981.326400519954;8984.595693193789;9001.639934402616;9045.864312035324;9047.592858321359;9049.547522623876;9052.321075919299;8981.595828187714;9030.864537031983;9072.77318067047;9086.136817002374;9097.090290970917;8939.597718102701;8971;9020.458772473672;9031.503267320295;9034.86447703285;42324.78837605823;42603.573964260584;42610;42647.786761065996;42667.14665706668;3372;3475.061733331986;3569.7679650822615;3809.980950095232;3851.383778595411;31585;32088.197795055075;32301.838490807397;32386;32503.187420314443;4111.588841115894;4336.501302350207;4343.782810859444;4501.7524036178;4533.455985281755;272718;366553;368859.15570422244;369148;371182;56876.71561642165;57242;57385.42614573884;57521;57535.712321438965;59841.700791495765;59895;59953.400465995655;59964.70017649884;59972;16871.156442177842;17150;17153.571160721083;17159.142042897805;17164.39924602633;13212.669683257995;13316.933415332862;13350.399232034582;13363.599092027254;13372.197668139943;14448.711025779425;15438.38246470151;15477.5356739298;15491.922540387226;15701.685966280611;5888.234529511136;6015.819525414245;6189.5126033625265;6228.906566401508;6278.434940855253;918.7932715139041;1022.3610243597751;1063.3779239145188;1076.3057827701089;1107.9390633515234;30933.69066309353;31031;31387.686123138934;31943;32091.839540802146;904.4618452021168;1099.4887377369562;1132.3545579020044;1160.5995931403652;1182.2355905700042;15694.450694225647;16497;16536.917315413346;16623.418180363627;16648.75026874598;486505;491991;492400;492823;493382;56978;57395.13907291394;57415.42584574184;57419.42580574224;58121.70939145277;1204.5783975608642;1270.1807334269529;1313.1005261395974;1345.387848528917;1360.4286199796109;27891.302717432227;32342.514862277556;32369;32375;32542;47650.76174619105;48284.27573586469;48415.27377089417;48422.27366589433;48474.515254847;386348;387773;388825;389077;389113;26688.599671004562;27197.59203611987;27288.590671140348;28298.292542685773;39096;46138.307925380475;46879.76560117178;46975.765121175544;47035.52964470448;47311.29033064576;38577.61422385852;39264.6073539261;39590.108182446646;40273.39589906095;41753.373699393924;39589.2082158361;39829.40255896106;40207.396889046104;41172.588274116875;41988;39212.41181382225;39732;39891.20217595574;40254;40674.1865162701;34310.65689343074;35997.46003809893;36989.26021479609;37003.25993480169;37082;26831.597526037516;27212.863935680194;27331.86334068317;27413.177604671095;27638.170854874385 14:37:42 INFO - PROCESS | 9431 | |2;jquery.html;370.6534390345029;387.57754048087577;395.55895176877783;401.24766063630693;418.58769112424255;13469.730605387886;15222;16894.155292235384;19280.3251886184;19857.503562410948;17970.820291797078;18264.543386415346;18448.44654660359;18519.351822686207;19698;5642.520385767215;5860.238169038041;5862.20860183874;5863.560232982522;5867.266591676041;5454.10007348787;5717.142428635696;5762.538996880239;5846.88306233875;5848.800995795852;5635.492805647491;5835.679037652927;5838.678872662002;5844.094165404369;5852.034414321632;5752.568557358215;5805.564582656297;5832;5859.6777177255235;5866.6480011199465;5602.691851948141;5622.297212848394;5699.316082070178;5703.74333155009;5781.624194427391;13178.86821131786;13348.866511334956;13356.332183390792;13410;20519.281825136048;2135.4251784060534;2190.7809219078126;2191.485001024758;2203.8787866667326;2229.175205174096;9547.61809527618;9681.806363872754;9754.90245097547;9885.950570247174;9926.751831204205;1735.8524525415355;1812.6918423867842;1886.4906475251605;1922.3055782515069;1930.3050901675385;83238;84675.5766221171;85568.14431855726;86521.56739216327;86553.56723216406;10829.945850270698;10890.40102794338;11016.834747478793;11045.005949464587;11343.376114313793;15629.765553516707;15653.530394088195;15676.764848527282;15687.529374118794;15706.293216805043;7877.448578599558;8022.318102961197;8305.667773289;8523.74428767138;8534.743957681156;7876.212378762192;7944.04671439431;8010.47455441306;8121.675132994732;8168.183181681903;4411.735295882258;4490.595846373777;4542.273236282245;4735.12400205963;4845.951540484621;1925.9422217333502;1944.4652720501692;1956.4228552577024;1989.2043182727025;1999.2402886902885;939.5490164720973;947.6351604632266;977.8973207813219;986.5461887531673;1017.1252722658508;17322.307107715802;20251.189952402034;20593.897030514752;20690.482737931674;20839.166433342798;978.9265805064649;989.7228775942748;998.6404894238068;1000.099910080933;1032.318669678024;10443.268971171945;10875;10929.726756831144;10933.671989840317;10957.726056848642;381787;426024;455461;456237;458121;72612.27387726161;81046.59476702697;95331.52334238423;96335.51832240794;96787;946.8343039967978;999.1706883287;1040.516159985595;1043.3218408034838;1054.8628678271775;15906.443274485337;17099.401520946707;17108.743368849726;17421.738673920154;18368.101716262136;40168.196636067696;43473.782631086644;43686;44814.10371792611;44913.77543112394;52707;58240.417595825194;58466.4153358478;59120.11319830292;59827.102593462005;7992.5204487730925;8333.749987500385;8425.831483370175;8462.534560599226;8487;12099.879001209874;13084.542041028519;13096.607101786962;13184.274864882524;13191.406386712813;11056.004959553678;11083.612073577386;11245.550177993116;11535.077193824642;11992.160548761503;10948.945255273673;11450.94274528622;11600.419979001184;11661.24201926872;12159.513619455123;10442.530086146293;10724.839127413252;10788.406637635006;10825.67522974312;11225.438728063727;7901.762947111596;8237.629306681094;8421.62102705392;8442.197991190827;8715.433496822687;8827.735167944971;11066.944665276622;11232.438378081226;11425.771484570429;12058 14:37:42 INFO - PROCESS | 9431 | |3;mootools.html;1452.1069542231523;1493.8954273200877;1635.501172142496;1766.266999195334;1779.777527809024;7657.693692252317;7933.968584084061;8186.590670466475;8815.867761983567;8817.735467935965;8190.467619604721;8477.32181425486;8519.616617252223;8692.608832602533;8705.347098967586;2375.3586531636447;2400.291913885408;2401.0395841663385;2401.315625046862;2402.147237730598;2006.257684656679;2392.270357540949;2394.078279862248;2442.31615147758;2444.168982545934;2089;2305.1816605105196;2394.892229849661;2398.928032159038;2413.5293617744546;2322.0479603362623;2334.323046316566;2334.789868911789;2341.461463863321;2379.3694670912223;2290.335802617239;2341.9297421077395;2342.5900467418305;2346.23747282134;2355.0462062864567;8578.82842343156;8635.222829945335;8674.219320261207;8730.738077857612;8731;5054.848354549369;5177.559907407875;5183;5189.455107213764;5193.506616871391;3247;3374.7300215982664;3420.9486857696907;3436.8281585920854;3484.4599087141532;1117.1844553475926;1132.0830127596673;1185.2769810415639;1186.4186548591215;1196.0252394298707;11950.402479875971;11991;12020.45907934145;12044.217125886702;12052.819207711891;4343.587359200871;4551.975805443749;4577.198990176772;4646.697964632288;4691.061787642485;10189.490525473844;10189.745256368502;10193.74515637115;10194.847077293847;10202.132818710345;5222.556082732935;5222.634415590872;5230.503102205284;5236.764345604457;5269.314989051399;4642.651801114863;4742.430908291027;4768.57082862544;4804.759762011886;4809.44691360491;3898.083950271684;3926.489556357655;3947.1908258806875;4019.070845993939;4058.370952502367;1135.4720055174384;1167.754771497978;1294.0812023463448;1296.8508621508458;1331.640457076584;900.8918929728475;918.1186061381015;960.5053397500293;962.485070487286;965.9275554333453;5742.7702891883855;5910.704464776744;5971.790987315423;6161.087709304624;6197.690115494207;975.0834215837091;981.5877331520778;987.521052289639;1022.1516141602452;1030.9587616495405;4051.6150965658803;4155.376693495939;4258.6805989550285;4348.630366418826;4362.276900624299;10404.58381664725;10660.520276587573;10730.624428144978;10736.248462607698;10813.891861081445;10601.57593696259;10602.469876506144;10610.734731631772;10615.150787936947;10619.36283822973;7620.504667196615;7735.303822655988;7808.726694565663;7980.840383192303;7981.563318602591;5864.648121112747;5910.320313163869;5994.67029313392;6050.273967123973;6066.241719785027;9707.320487565803;9804.1666458349;9815.85276220843;9851.113399794052;9854.113129818064;9670.854937175807;9905.801883962424;9952.054554817281;10146.391216527032;10151.695449136538;7610.315071643578;7723.729669461766;7772.883406749016;7776.88334674969;7780.533168009937;8450.873236901574;8456.619452124545;8477.109903460048;8511.74464766058;8521.06268310473;7296.598687072262;7437.628118593941;7540.924590754023;7600.201978792147;7634.4655874088285;7162.677679504539;7294.817629559091;7340.376068034277;7422.183559808309;7505.986691796537;6957.547759395623;7047.436205103477;7047.929520704727;7051.1186101737285;7071.116110486189;5155.639105262596;5216.817411390583;5280.841574752763;5312.574994000548;5532.668039917457;5285.735713214247;5313.2561441397465;5329.387120481194;5361.946380536145;5559.722013899208 14:37:42 INFO - PROCESS | 9431 | |4;prototype.html;283.8836077208344;291.2253405940199;295.13672507914345;295.7397490208616;303.7084398976982;5041.798328066882;5188.221766734982;5196.402413722419;5230.63242192769;5261.421243663194;5019.949800501994;5122.92315615266;5124.590032797368;5127.33344665193;5145.691258524491;4037.777922214277;4038.7778672173017;4039.313316736155;4048.1296521247928;4053.412255222991;3692.7968961707097;3803.904902377435;4047.6845025366724;4055.2903241932545;4062.9187416251657;3924.4309575111733;3942.5860284669984;3960.2673505401594;3963.8612648557305;3964.484616999786;3918.686505079587;3928.666063384616;3938.113924367023;3945.506811648544;3949.4794504115835;3433.969809057273;3438.381091403546;3441.587009558844;3443.500692399588;3454.4990976308545;4729.456112547033;4957.454679985199;5005.274235235906;5024.346834911438;5036.194208926554;4521.480029796583;4533.9319910201375;4555.63554915606;4563.77181140945;4565.61192298655;5407.3781515125875;5411.756470958777;5424.403315635277;5424.403315635316;5446.564274858002;3644.2529281497227;3816.5992570779945;3817.48463957365;3848.8652897148745;3854.5759966403675;5503.147012213113;5550.47270509301;5551.555875529948;5555.444455554452;5560.138178582326;3933.488646475997;3942.42834788954;3944.1322908959983;3947.2697550953167;3956.4263181839046;3889.844406223776;3949.269385163754;3978.1844721831953;3992.2215168042535;4006.1386801837743;4522.683412161183;4531.864044078683;4537.954620453819;4542.545745425497;4542.772861356919;5376.65583604099;5378.78484860609;5391.16436952273;5411.377691565521;5422.972885135628;1297.195738642048;1317.9209247445183;1360.9863901361057;1380.171896861895;1393.965150871216;861.9353548483764;878.7846977490556;890.2077151335317;892.3352102683542;902.9774255643662;4631.467381251199;4638.512956139624;4645.279981602857;4653.720776753338;4690.601298889633;4835.903281934412;4874.36633237684;4884.65807393479;4904.239842824368;4918.6556941014505;4645.46577143626;4657.394538709946;4658.58072773452;4660.347551342815;4711.303930584987;2736.644236249315;2934.2224310557717;2945.4550908082074;2952.4647183464476;2954.896578619738;5746.678263999303;5772.855678608068;5793.449622285876;5813.157092221604;5813.331466881363;5547.639403438763;5683.715814209273;5711.971440142773;5722.971385143131;5732.254806875079;5772.797952071657;5782.103773915134;5782.306123265235;5790.420957904176;5790.739416726257;5545.390007099135;5560.694161820977;5562.972185139049;5567.109262517978;5571.275734154534;5295.676080979755;5483.437220392169;5500.697461639648;5522.751476183662;5524.75138618755;5778.16216648592;5783.265020493919;5789.1474728086605;5796.362400136066;5811.157382179645;3842.884713458601;3843.7117216208335;3856.710746694065;3860.575336712903;3862.9806850965565;5279.419263880894;5324.7220667038855;5595.216669666169;5612.887742245214;5621.409751975983;5298.953101966307;5376.435474275301;5474.20815384149;5478.287822583198;5489.807856724996;5413.485718856702;5424.457554244544;5444.401115877171;5446.319210098738;5474.753636086465;5276.649758777481;5399.271098401822;5407.405185429679;5413.132469298086;5439.945600543944;5399.433059528693;5459.2629994950175;5459.399466058651;5468.972655136698;5478.506934375925;5307.52232299095;5329.62692611529;5346.021971870433;5367.302250707383;5371.597130215171 14:37:42 INFO - PROCESS | 9431 | |5;yui.html;690.2821066091266;728.4136270302405;751.2750196060801;768.5849641193755;769.9836216194625;14365.35355908984;15450.61373465664;15521.611959700986;15541.067535947837;15560.922195389021;15412.61468463289;15558.299876505556;15575.688486230269;15583.142927139002;15585.454509092184;660.7951535024134;679.8776220280348;775.3642013548892;778.4550814429894;798.3613109512396;793.714262865368;811.0429692962302;812.6627449608405;812.9105798362175;813.2680587471264;858.7252079334615;865.709987154304;865.8268346330727;865.8441480533502;866.2463656618751;683.193831279088;716.5521549031855;794.9006374203225;850.9872351914727;852.7186028610544;618.3322012226785;621.8631900981777;622.4118208293165;622.5735371270694;628.1990462160782;15338.769918451233;15359.078455292718;15362.385504579803;15371.385144594204;15377.538673839803;14187.43250269988;14202.928985355007;14437;14530.12819230839;14566.563003109924;11839.971345279437;11978.880211197864;12309.507619695201;12463.252204867737;12796.168249063874;4190.455240818674;4369.366441865912;4396.20868243716;4538.818447262105;4539.591436770706;79086.60456697794;79540;79631;80702.19297807064;80730.59634701788;10149.543270552844;10270.178385729123;10392.844107338396;10393.53229104692;10418.635347762793;20891.37325880226;20953.47616309574;20981.370558882954;21064;21401;6964.93035069653;7586.848263034708;7669.808254793563;7696.230376962259;7821.569813660303;3891.124025232228;4322.589354011427;4338.349247612883;4348.391225228471;4387.14450682114;3202.59967504062;3300.603927528712;3307.818070006125;3315.4032274190395;3321.8339083046235;542.0432935868196;557.4481263549058;559.5439716630933;559.6390328238264;561.7331767410448;514;600.7506884642912;636.3318515558645;684.5174152222671;708.9255628159073;8652.3510736695;9140.268778497704;9537.523123843781;9857.162141217934;9859.556319965619;611.5230120506012;656.917885264342;670.3196255800367;690.975815846453;728.8287252495659;4359.607635312837;4629.699069560468;4790.233562629963;4791.760411979387;4798.4001999750035;93128;94537.52731236437;95223.52388238015;128827;132745;93441;94245;94429.52785236167;94930;95539.52230238804;53322.20016699753;53455.73272133692;82232.58883705662;84156.27265228561;85753.57123214469;10123.797524049625;10230.084425938572;10401.53193106297;10477.52851121686;10569.524371403151;44048.779756101016;46541.30188047114;51968.740156298976;53470;55768.16347754706;22128.33614991553;43139.784301078296;72190;76164.61917690375;80310;12005.459754311252;12080.637580872588;12177.939110304393;12337.876621233672;12509.56216532417;14577.562673119823;14619.415223390944;14666.85333146655;14699.706005880036;14717.852821472077;13600.65998350049;13653.863461365656;13819.585412437644;14200.928995354958;14835.332410041357;13605.387757550736;13606.795898061338;14204.644883877985;14284.357203926056;15388.307526161127;12660.493580256687;12860.485580576673;13265.535706250235;13354.198748075147;13384;9419.397919978373;9617.855732164164;9688.176504997155;9690.903090969283;9892.060254275832;9520.190783783455;10181.037281194715;10396.948015259875;10474.476276186313;10687 14:37:42 INFO - PROCESS | 9431 | __end_tp_report 14:37:42 INFO - PROCESS | 9431 | __start_cc_report 14:37:42 INFO - PROCESS | 9431 | _x_x_mozilla_cycle_collect,1169 14:37:42 INFO - PROCESS | 9431 | __end_cc_report 14:37:42 INFO - PROCESS | 9431 | __startTimestamp1474061862697__endTimestamp 14:37:42 INFO - PROCESS | 9431 | 14:37:42 INFO - PROCESS | 9431 | ------- Summary: start ------- 14:37:42 INFO - PROCESS | 9431 | Number of tests: 6 14:37:42 INFO - PROCESS | 9431 | 14:37:42 INFO - PROCESS | 9431 | [#0] dojo.html Cycles:165 Average:5184.10 Median:5381.43 stddev:571.28 (10.6%) stddev-sans-first:546.93 14:37:42 INFO - PROCESS | 9431 | Values: 3008.1 3030.3 3034.7 3066.3 3090.9 5095.2 5160.2 5221.1 5221.3 5225.5 5153.9 5182.9 5200.9 5234.8 5235.2 4670.9 4738.4 4749.0 4752.5 4833.0 4860.1 4885.7 4889.3 4901.6 4931.9 4623.0 4672.6 4735.6 4746.7 4770.4 4796.3 4809.4 4844.7 4857.8 4879.6 4707.4 4736.7 4752.9 4766.9 4806.9 5049.1 5087.4 5231.6 5232.6 5284.5 5504.0 5560.4 5561.1 5574.6 5652.9 5590.7 5599.8 5611.2 5611.9 5613.2 5242.6 5244.1 5250.4 5253.8 5314.1 5518.0 5570.9 5657.5 5685.7 5707.7 5439.9 5493.8 5495.0 5495.7 5551.9 5520.9 5563.6 5573.7 5585.4 5586.9 5390.8 5433.7 5462.3 5491.0 5634.5 5537.2 5543.5 5596.5 5604.1 5609.7 4308.4 4311.7 4313.6 4317.1 4371.4 3808.4 3810.7 3823.6 3832.4 3900.6 5271.6 5283.5 5285.0 5345.4 5355.6 5552.3 5588.8 5596.5 5600.5 5601.4 5094.9 5168.6 5295.8 5306.4 5375.7 4709.4 4798.9 4866.6 4869.2 4905.9 5846.3 5856.8 5858.4 5860.4 5865.1 5649.8 5654.3 5657.7 5662.5 5681.1 5569.7 5615.6 5652.5 5718.0 5727.5 5418.3 5457.0 5466.7 5473.9 5537.2 5564.3 5568.4 5570.6 5572.9 5587.9 5295.2 5347.4 5385.2 5427.9 5455.5 5420.5 5421.4 5428.9 5475.4 5486.5 5420.6 5425.5 5426.6 5428.8 5488.4 5251.1 5313.4 5381.4 5439.2 5450.6 5284.8 5326.7 5338.2 5385.3 5408.2 14:37:42 INFO - PROCESS | 9431 | 14:37:42 INFO - PROCESS | 9431 | [#1] ext.html Cycles:180 Average:57295.22 Median:22392.89 stddev:109646.10 (489.6%) stddev-sans-first:109870.98 14:37:42 INFO - PROCESS | 9431 | Values: 573.7 573.7 583.2 585.4 596.7 32004.2 42649.8 43926.8 44060.6 46949.3 44459.3 44805.6 44948.6 44957.0 44987.6 8880.8 9037.1 9041.4 9041.7 9049.1 8882.8 8979.1 8981.2 8981.3 8984.6 9001.6 9045.9 9047.6 9049.5 9052.3 8981.6 9030.9 9072.8 9086.1 9097.1 8939.6 8971.0 9020.5 9031.5 9034.9 42324.8 42603.6 42610.0 42647.8 42667.1 3372.0 3475.1 3569.8 3810.0 3851.4 31585.0 32088.2 32301.8 32386.0 32503.2 4111.6 4336.5 4343.8 4501.8 4533.5 272718.0 366553.0 368859.2 369148.0 371182.0 56876.7 57242.0 57385.4 57521.0 57535.7 59841.7 59895.0 59953.4 59964.7 59972.0 16871.2 17150.0 17153.6 17159.1 17164.4 13212.7 13316.9 13350.4 13363.6 13372.2 14448.7 15438.4 15477.5 15491.9 15701.7 5888.2 6015.8 6189.5 6228.9 6278.4 918.8 1022.4 1063.4 1076.3 1107.9 30933.7 31031.0 31387.7 31943.0 32091.8 904.5 1099.5 1132.4 1160.6 1182.2 15694.5 16497.0 16536.9 16623.4 16648.8 486505.0 491991.0 492400.0 492823.0 493382.0 56978.0 57395.1 57415.4 57419.4 58121.7 1204.6 1270.2 1313.1 1345.4 1360.4 27891.3 32342.5 32369.0 32375.0 32542.0 47650.8 48284.3 48415.3 48422.3 48474.5 386348.0 387773.0 388825.0 389077.0 389113.0 26688.6 27197.6 27288.6 28298.3 39096.0 46138.3 46879.8 46975.8 47035.5 47311.3 38577.6 39264.6 39590.1 40273.4 41753.4 39589.2 39829.4 40207.4 41172.6 41988.0 39212.4 39732.0 39891.2 40254.0 40674.2 34310.7 35997.5 36989.3 37003.3 37082.0 26831.6 27212.9 27331.9 27413.2 27638.2 14:37:42 INFO - PROCESS | 9431 | 14:37:42 INFO - PROCESS | 9431 | [#2] jquery.html Cycles:180 Average:27179.16 Median:20293.39 stddev:72399.08 (356.8%) stddev-sans-first:72574.19 14:37:42 INFO - PROCESS | 9431 | Values: 370.7 387.6 395.6 401.2 418.6 13469.7 15222.0 16894.2 19280.3 19857.5 17970.8 18264.5 18448.4 18519.4 19698.0 5642.5 5860.2 5862.2 5863.6 5867.3 5454.1 5717.1 5762.5 5846.9 5848.8 5635.5 5835.7 5838.7 5844.1 5852.0 5752.6 5805.6 5832.0 5859.7 5866.6 5602.7 5622.3 5699.3 5703.7 5781.6 13178.9 13348.9 13356.3 13410.0 20519.3 2135.4 2190.8 2191.5 2203.9 2229.2 9547.6 9681.8 9754.9 9886.0 9926.8 1735.9 1812.7 1886.5 1922.3 1930.3 83238.0 84675.6 85568.1 86521.6 86553.6 10829.9 10890.4 11016.8 11045.0 11343.4 15629.8 15653.5 15676.8 15687.5 15706.3 7877.4 8022.3 8305.7 8523.7 8534.7 7876.2 7944.0 8010.5 8121.7 8168.2 4411.7 4490.6 4542.3 4735.1 4846.0 1925.9 1944.5 1956.4 1989.2 1999.2 939.5 947.6 977.9 986.5 1017.1 17322.3 20251.2 20593.9 20690.5 20839.2 978.9 989.7 998.6 1000.1 1032.3 10443.3 10875.0 10929.7 10933.7 10957.7 381787.0 426024.0 455461.0 456237.0 458121.0 72612.3 81046.6 95331.5 96335.5 96787.0 946.8 999.2 1040.5 1043.3 1054.9 15906.4 17099.4 17108.7 17421.7 18368.1 40168.2 43473.8 43686.0 44814.1 44913.8 52707.0 58240.4 58466.4 59120.1 59827.1 7992.5 8333.7 8425.8 8462.5 8487.0 12099.9 13084.5 13096.6 13184.3 13191.4 11056.0 11083.6 11245.6 11535.1 11992.2 10948.9 11450.9 11600.4 11661.2 12159.5 10442.5 10724.8 10788.4 10825.7 11225.4 7901.8 8237.6 8421.6 8442.2 8715.4 8827.7 11066.9 11232.4 11425.8 12058.0 14:37:42 INFO - PROCESS | 9431 | 14:37:42 INFO - PROCESS | 9431 | [#3] mootools.html Cycles:180 Average:5730.79 Median:5166.60 stddev:3186.78 (61.7%) stddev-sans-first:3179.49 14:37:42 INFO - PROCESS | 9431 | Values: 1452.1 1493.9 1635.5 1766.3 1779.8 7657.7 7934.0 8186.6 8815.9 8817.7 8190.5 8477.3 8519.6 8692.6 8705.3 2375.4 2400.3 2401.0 2401.3 2402.1 2006.3 2392.3 2394.1 2442.3 2444.2 2089.0 2305.2 2394.9 2398.9 2413.5 2322.0 2334.3 2334.8 2341.5 2379.4 2290.3 2341.9 2342.6 2346.2 2355.0 8578.8 8635.2 8674.2 8730.7 8731.0 5054.8 5177.6 5183.0 5189.5 5193.5 3247.0 3374.7 3420.9 3436.8 3484.5 1117.2 1132.1 1185.3 1186.4 1196.0 11950.4 11991.0 12020.5 12044.2 12052.8 4343.6 4552.0 4577.2 4646.7 4691.1 10189.5 10189.7 10193.7 10194.8 10202.1 5222.6 5222.6 5230.5 5236.8 5269.3 4642.7 4742.4 4768.6 4804.8 4809.4 3898.1 3926.5 3947.2 4019.1 4058.4 1135.5 1167.8 1294.1 1296.9 1331.6 900.9 918.1 960.5 962.5 965.9 5742.8 5910.7 5971.8 6161.1 6197.7 975.1 981.6 987.5 1022.2 1031.0 4051.6 4155.4 4258.7 4348.6 4362.3 10404.6 10660.5 10730.6 10736.2 10813.9 10601.6 10602.5 10610.7 10615.2 10619.4 7620.5 7735.3 7808.7 7980.8 7981.6 5864.6 5910.3 5994.7 6050.3 6066.2 9707.3 9804.2 9815.9 9851.1 9854.1 9670.9 9905.8 9952.1 10146.4 10151.7 7610.3 7723.7 7772.9 7776.9 7780.5 8450.9 8456.6 8477.1 8511.7 8521.1 7296.6 7437.6 7540.9 7600.2 7634.5 7162.7 7294.8 7340.4 7422.2 7506.0 6957.5 7047.4 7047.9 7051.1 7071.1 5155.6 5216.8 5280.8 5312.6 5532.7 5285.7 5313.3 5329.4 5361.9 5559.7 14:37:42 INFO - PROCESS | 9431 | 14:37:42 INFO - PROCESS | 9431 | [#4] prototype.html Cycles:180 Average:4494.28 Median:5039.00 stddev:1343.77 (26.7%) stddev-sans-first:1309.85 14:37:42 INFO - PROCESS | 9431 | Values: 283.9 291.2 295.1 295.7 303.7 5041.8 5188.2 5196.4 5230.6 5261.4 5019.9 5122.9 5124.6 5127.3 5145.7 4037.8 4038.8 4039.3 4048.1 4053.4 3692.8 3803.9 4047.7 4055.3 4062.9 3924.4 3942.6 3960.3 3963.9 3964.5 3918.7 3928.7 3938.1 3945.5 3949.5 3434.0 3438.4 3441.6 3443.5 3454.5 4729.5 4957.5 5005.3 5024.3 5036.2 4521.5 4533.9 4555.6 4563.8 4565.6 5407.4 5411.8 5424.4 5424.4 5446.6 3644.3 3816.6 3817.5 3848.9 3854.6 5503.1 5550.5 5551.6 5555.4 5560.1 3933.5 3942.4 3944.1 3947.3 3956.4 3889.8 3949.3 3978.2 3992.2 4006.1 4522.7 4531.9 4538.0 4542.5 4542.8 5376.7 5378.8 5391.2 5411.4 5423.0 1297.2 1317.9 1361.0 1380.2 1394.0 861.9 878.8 890.2 892.3 903.0 4631.5 4638.5 4645.3 4653.7 4690.6 4835.9 4874.4 4884.7 4904.2 4918.7 4645.5 4657.4 4658.6 4660.3 4711.3 2736.6 2934.2 2945.5 2952.5 2954.9 5746.7 5772.9 5793.4 5813.2 5813.3 5547.6 5683.7 5712.0 5723.0 5732.3 5772.8 5782.1 5782.3 5790.4 5790.7 5545.4 5560.7 5563.0 5567.1 5571.3 5295.7 5483.4 5500.7 5522.8 5524.8 5778.2 5783.3 5789.1 5796.4 5811.2 3842.9 3843.7 3856.7 3860.6 3863.0 5279.4 5324.7 5595.2 5612.9 5621.4 5299.0 5376.4 5474.2 5478.3 5489.8 5413.5 5424.5 5444.4 5446.3 5474.8 5276.6 5399.3 5407.4 5413.1 5439.9 5399.4 5459.3 5459.4 5469.0 5478.5 5307.5 5329.6 5346.0 5367.3 5371.6 14:37:42 INFO - PROCESS | 9431 | 14:37:42 INFO - PROCESS | 9431 | [#5] yui.html Cycles:180 Average:19639.19 Median:25585.50 stddev:28263.36 (110.5%) stddev-sans-first:28306.83 14:37:42 INFO - PROCESS | 9431 | Values: 690.3 728.4 751.3 768.6 770.0 14365.4 15450.6 15521.6 15541.1 15560.9 15412.6 15558.3 15575.7 15583.1 15585.5 660.8 679.9 775.4 778.5 798.4 793.7 811.0 812.7 812.9 813.3 858.7 865.7 865.8 865.8 866.2 683.2 716.6 794.9 851.0 852.7 618.3 621.9 622.4 622.6 628.2 15338.8 15359.1 15362.4 15371.4 15377.5 14187.4 14202.9 14437.0 14530.1 14566.6 11840.0 11978.9 12309.5 12463.3 12796.2 4190.5 4369.4 4396.2 4538.8 4539.6 79086.6 79540.0 79631.0 80702.2 80730.6 10149.5 10270.2 10392.8 10393.5 10418.6 20891.4 20953.5 20981.4 21064.0 21401.0 6964.9 7586.8 7669.8 7696.2 7821.6 3891.1 4322.6 4338.3 4348.4 4387.1 3202.6 3300.6 3307.8 3315.4 3321.8 542.0 557.4 559.5 559.6 561.7 514.0 600.8 636.3 684.5 708.9 8652.4 9140.3 9537.5 9857.2 9859.6 611.5 656.9 670.3 691.0 728.8 4359.6 4629.7 4790.2 4791.8 4798.4 93128.0 94537.5 95223.5 128827.0 132745.0 93441.0 94245.0 94429.5 94930.0 95539.5 53322.2 53455.7 82232.6 84156.3 85753.6 10123.8 10230.1 10401.5 10477.5 10569.5 44048.8 46541.3 51968.7 53470.0 55768.2 22128.3 43139.8 72190.0 76164.6 80310.0 12005.5 12080.6 12177.9 12337.9 12509.6 14577.6 14619.4 14666.9 14699.7 14717.9 13600.7 13653.9 13819.6 14200.9 14835.3 13605.4 13606.8 14204.6 14284.4 15388.3 12660.5 12860.5 13265.5 13354.2 13384.0 9419.4 9617.9 9688.2 9690.9 9892.1 9520.2 10181.0 10396.9 10474.5 10687.0 14:37:42 INFO - PROCESS | 9431 | -------- Summary: end -------- 14:37:42 INFO - PROCESS | 9431 | 14:37:43 INFO - TEST-INFO | 9431: exit 0 14:37:43 INFO - TEST-OK | dromaeo_css | took 1116219ms 14:37:43 INFO - TEST-START | kraken 14:37:43 INFO - operating with platform_type : linux_ 14:37:43 INFO - Initialising browser for kraken test... 14:37:43 INFO - TEST-INFO | started process 9737 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHL2fB7/profile http://localhost:44486/getInfo.html) 14:37:47 INFO - PROCESS | 9737 | __metrics Screen width/height:1600/1200 14:37:47 INFO - PROCESS | 9737 | colorDepth:24 14:37:47 INFO - PROCESS | 9737 | Browser inner width/height: 1024/697 14:37:47 INFO - PROCESS | 9737 | __metrics 14:37:48 INFO - PROCESS | 9737 | 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" 14:37:48 INFO - PROCESS | 9737 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:37:48 INFO - PROCESS | 9737 | console.error: PushService: 14:37:48 INFO - PROCESS | 9737 | stateChangeProcessEnqueue: Error transitioning state 14:37:48 INFO - PROCESS | 9737 | UnknownError 14:37:49 INFO - TEST-INFO | 9737: exit 0 14:37:49 INFO - Browser initialized. 14:37:49 INFO - Running cycle 1/1 for kraken test... 14:37:49 INFO - TEST-INFO | started process 9819 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHL2fB7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:01 INFO - PROCESS | 9819 | RSS: Main: 173101056 14:38:01 INFO - PROCESS | 9819 | 14:38:04 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44486/tests/kraken/driver.html?testName=audio-beat-detection) 14:38:05 INFO - PROCESS | 9819 | RSS: Main: 232685568 14:38:05 INFO - PROCESS | 9819 | 14:38:07 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44486/tests/kraken/driver.html?testName=audio-dft) 14:38:08 INFO - PROCESS | 9819 | RSS: Main: 216469504 14:38:08 INFO - PROCESS | 9819 | 14:38:11 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44486/tests/kraken/driver.html?testName=audio-fft) 14:38:12 INFO - PROCESS | 9819 | RSS: Main: 298881024 14:38:12 INFO - PROCESS | 9819 | 14:38:14 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44486/tests/kraken/driver.html?testName=audio-oscillator) 14:38:14 INFO - PROCESS | 9819 | RSS: Main: 208089088 14:38:14 INFO - PROCESS | 9819 | 14:38:16 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44486/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:38:17 INFO - PROCESS | 9819 | RSS: Main: 212299776 14:38:17 INFO - PROCESS | 9819 | 14:38:20 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44486/tests/kraken/driver.html?testName=imaging-darkroom) 14:38:21 INFO - PROCESS | 9819 | RSS: Main: 248995840 14:38:21 INFO - PROCESS | 9819 | 14:38:24 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44486/tests/kraken/driver.html?testName=imaging-desaturate) 14:38:25 INFO - PROCESS | 9819 | RSS: Main: 247762944 14:38:25 INFO - PROCESS | 9819 | 14:38:27 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44486/tests/kraken/driver.html?testName=json-parse-financial) 14:38:28 INFO - PROCESS | 9819 | RSS: Main: 248131584 14:38:28 INFO - PROCESS | 9819 | 14:38:29 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44486/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:38:30 INFO - PROCESS | 9819 | RSS: Main: 209985536 14:38:30 INFO - PROCESS | 9819 | 14:38:32 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44486/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:38:32 INFO - PROCESS | 9819 | RSS: Main: 216416256 14:38:32 INFO - PROCESS | 9819 | 14:38:34 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44486/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:38:35 INFO - PROCESS | 9819 | RSS: Main: 221044736 14:38:35 INFO - PROCESS | 9819 | 14:38:38 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:38:38 INFO - PROCESS | 9819 | RSS: Main: 223731712 14:38:38 INFO - PROCESS | 9819 | 14:38:41 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:38:42 INFO - PROCESS | 9819 | RSS: Main: 221413376 14:38:42 INFO - PROCESS | 9819 | 14:38:43 INFO - PROCESS | 9819 | Cycle 1(1): loaded http://localhost:44486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44486/tests/kraken/driver.html?testName=ai-astar) 14:38:44 INFO - PROCESS | 9819 | RSS: Main: 272273408 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | __start_tp_report 14:38:44 INFO - PROCESS | 9819 | _x_x_mozilla_page_load 14:38:44 INFO - PROCESS | 9819 | _x_x_mozilla_page_load_details 14:38:44 INFO - PROCESS | 9819 | |i|pagename|runs| 14:38:44 INFO - PROCESS | 9819 | |0;ai-astar;109;111;110;110;111;111;111;111;111;112 14:38:44 INFO - PROCESS | 9819 | |1;audio-beat-detection;151;120;124;157;119;118;118;126;152;120 14:38:44 INFO - PROCESS | 9819 | |2;audio-dft;160;161;160;161;160;160;161;161;161;163 14:38:44 INFO - PROCESS | 9819 | |3;audio-fft;76;76;77;110;77;77;76;86;109;76 14:38:44 INFO - PROCESS | 9819 | |4;audio-oscillator;82;82;126;82;81;82;105;81;82;110 14:38:44 INFO - PROCESS | 9819 | |5;imaging-gaussian-blur;117;118;118;116;117;117;117;117;117;117 14:38:44 INFO - PROCESS | 9819 | |6;imaging-darkroom;147;146;147;147;147;147;146;145;145;146 14:38:44 INFO - PROCESS | 9819 | |7;imaging-desaturate;95;95;95;96;95;95;95;96;96;95 14:38:44 INFO - PROCESS | 9819 | |8;json-parse-financial;52;50;51;50;52;52;53;53;52;53 14:38:44 INFO - PROCESS | 9819 | |9;json-stringify-tinderbox;62;62;62;61;62;62;62;62;62;63 14:38:44 INFO - PROCESS | 9819 | |10;stanford-crypto-aes;79;86;80;77;82;77;86;77;77;78 14:38:44 INFO - PROCESS | 9819 | |11;stanford-crypto-ccm;127;124;125;123;123;124;135;138;180;144 14:38:44 INFO - PROCESS | 9819 | |12;stanford-crypto-pbkdf2;173;163;165;164;172;164;167;165;163;167 14:38:44 INFO - PROCESS | 9819 | |13;stanford-crypto-sha256-iterative;68;66;66;66;65;69;65;67;65;66 14:38:44 INFO - PROCESS | 9819 | __end_tp_report 14:38:44 INFO - PROCESS | 9819 | __start_cc_report 14:38:44 INFO - PROCESS | 9819 | _x_x_mozilla_cycle_collect,1639 14:38:44 INFO - PROCESS | 9819 | __end_cc_report 14:38:44 INFO - PROCESS | 9819 | __startTimestamp1474061924242__endTimestamp 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | ------- Summary: start ------- 14:38:44 INFO - PROCESS | 9819 | Number of tests: 14 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:0.82 (0.7%) stddev-sans-first:0.60 14:38:44 INFO - PROCESS | 9819 | Values: 109.0 111.0 110.0 110.0 111.0 111.0 111.0 111.0 111.0 112.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:125.00 stddev:16.03 (12.8%) stddev-sans-first:15.19 14:38:44 INFO - PROCESS | 9819 | Values: 151.0 120.0 124.0 157.0 119.0 118.0 118.0 126.0 152.0 120.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 14:38:44 INFO - PROCESS | 9819 | Values: 160.0 161.0 160.0 161.0 160.0 160.0 161.0 161.0 161.0 163.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#3] audio-fft Cycles:10 Average:84.00 Median:76.50 stddev:13.78 (18.0%) stddev-sans-first:14.30 14:38:44 INFO - PROCESS | 9819 | Values: 76.0 76.0 77.0 110.0 77.0 77.0 76.0 86.0 109.0 76.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:82.00 stddev:16.28 (19.9%) stddev-sans-first:16.92 14:38:44 INFO - PROCESS | 9819 | Values: 82.0 82.0 126.0 82.0 81.0 82.0 105.0 81.0 82.0 110.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 14:38:44 INFO - PROCESS | 9819 | Values: 117.0 118.0 118.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:147.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 14:38:44 INFO - PROCESS | 9819 | Values: 147.0 146.0 147.0 147.0 147.0 147.0 146.0 145.0 145.0 146.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 14:38:44 INFO - PROCESS | 9819 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 96.0 95.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.14 (2.2%) stddev-sans-first:1.20 14:38:44 INFO - PROCESS | 9819 | Values: 52.0 50.0 51.0 50.0 52.0 52.0 53.0 53.0 52.0 53.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 14:38:44 INFO - PROCESS | 9819 | Values: 62.0 62.0 62.0 61.0 62.0 62.0 62.0 62.0 62.0 63.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:79.50 stddev:3.60 (4.5%) stddev-sans-first:3.81 14:38:44 INFO - PROCESS | 9819 | Values: 79.0 86.0 80.0 77.0 82.0 77.0 86.0 77.0 77.0 78.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:131.00 stddev:17.65 (13.5%) stddev-sans-first:18.52 14:38:44 INFO - PROCESS | 9819 | Values: 127.0 124.0 125.0 123.0 123.0 124.0 135.0 138.0 180.0 144.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:166.00 stddev:3.56 (2.1%) stddev-sans-first:2.83 14:38:44 INFO - PROCESS | 9819 | Values: 173.0 163.0 165.0 164.0 172.0 164.0 167.0 165.0 163.0 167.0 14:38:44 INFO - PROCESS | 9819 | 14:38:44 INFO - PROCESS | 9819 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:1.34 (2.0%) stddev-sans-first:1.27 14:38:44 INFO - PROCESS | 9819 | Values: 68.0 66.0 66.0 66.0 65.0 69.0 65.0 67.0 65.0 66.0 14:38:44 INFO - PROCESS | 9819 | -------- Summary: end -------- 14:38:44 INFO - PROCESS | 9819 | 14:38:45 INFO - TEST-INFO | 9819: exit 0 14:38:45 INFO - TEST-OK | kraken | took 61895ms 14:38:45 INFO - SUITE-END | took 1178s 14:38:45 INFO - Completed test suite (00:19:38) 14:38:45 INFO - javascript benchmark 14:38:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3008.0825348268777, 3030.287882347648, 3034.711702388274, 3066.264096616813, 3090.9072727818175, 5095.235714642796, 5160.200168973805, 5221.06020916235, 5221.295125158103, 5225.503577160169, 5153.948460515394, 5182.8704282392955, 5200.869978250536, 5234.79060837567, 5235.162374020158, 4670.883227919295, 4738.407699037621, 4748.976255118737, 4752.548507891762, 4832.951670483303, 4860.1494738420815, 4885.658003939726, 4889.26661000851, 4901.632377571678, 4931.876703082416, 4622.976885115553, 4672.6261899047995, 4735.621150307967, 4746.691465054795, 4770.356001939728, 4796.25658023007, 4809.398825146856, 4844.66087373884, 4857.757112144379, 4879.585235255007, 4707.411573553306, 4736.73947932867, 4752.881177970544, 4766.904661906777, 4806.927896081562, 5049.141645920203, 5087.414947281073, 5231.633785635007, 5232.555232805216, 5284.524392804666, 5504.0, 5560.388357280696, 5561.082421400464, 5574.637648552831, 5652.858678533028, 5590.720463976825, 5599.804006859781, 5611.158326251054, 5611.915821262684, 5613.158026296048, 5242.606804489678, 5244.082285600043, 5250.396204436539, 5253.7635806388425, 5314.070037743418, 5518.0, 5570.860728481739, 5657.547396208293, 5685.687287199244, 5707.686077265707, 5439.945600543944, 5493.7802487900035, 5495.0, 5495.697736624444, 5551.944480555143, 5520.861978450491, 5563.582731295169, 5573.721313934287, 5585.357683866408, 5586.888262234814, 5390.838274851761, 5433.701146436905, 5462.289902312674, 5491.039068163055, 5634.464725851038, 5537.169424586345, 5543.501084902378, 5596.496315331558, 5604.075327570987, 5609.663420194802, 4308.353746937984, 4311.676624253194, 4313.61177494027, 4317.11499142675, 4371.409859668967, 3808.4287356896134, 3810.6951443884423, 3823.5794062653085, 3832.3868181090893, 3900.551436584829, 5271.551918086929, 5283.52448279649, 5285.0, 5345.438728933484, 5355.571554275649, 5552.333719953551, 5588.832335029955, 5596.524295434852, 5600.523955463831, 5601.355844077902, 5094.923576146361, 5168.638195326366, 5295.788168473295, 5306.416294207634, 5375.650582712111, 4709.411323584552, 4798.880027999258, 4866.635002374836, 4869.245266983623, 4905.9264111038365, 5846.327672317738, 5856.765729370777, 5858.414158584278, 5860.38465961068, 5865.061590145556, 5649.802256920988, 5654.264945557051, 5657.745401456862, 5662.462066103713, 5681.0910254359105, 5569.721513924369, 5615.634983726045, 5652.463016013472, 5718.027935251101, 5727.455891690282, 5418.349798024262, 5456.972715136399, 5466.72666366688, 5473.890522189614, 5537.224788529528, 5564.276644036249, 5568.415316391721, 5570.61005729595, 5572.888542229214, 5587.916181257203, 5295.179247216687, 5347.438518955453, 5385.246065550904, 5427.945720542744, 5455.481729235717, 5420.53925416344, 5421.403645598902, 5428.864278392914, 5475.4250803665045, 5486.451354864482, 5420.647657902224, 5425.538829199562, 5426.593005524681, 5428.782848686008, 5488.396276409512, 5251.107311756934, 5313.442088580798, 5381.434949330264, 5439.238506609157, 5450.645708028966, 5284.7886084556185, 5326.707031113326, 5338.172583249602, 5385.3268341457315, 5408.161734931092], "value": 5146.606991371943, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.6586730895118, 573.7131434282858, 583.2417856786177, 585.4058130997038, 596.7076132694981, 32004.199895002606, 42649.78675106624, 43926.7803660982, 44060.559394406046, 46949.29576056362, 44459.333110003376, 44805.55194448055, 44948.55051449485, 44957.0, 44987.550124498746, 8880.777980550489, 9037.096290370942, 9041.3671043027, 9041.728748137566, 9049.095090490931, 8882.777930551723, 8979.14698103679, 8981.191692747652, 8981.326400519954, 8984.595693193789, 9001.639934402616, 9045.864312035324, 9047.592858321359, 9049.547522623876, 9052.321075919299, 8981.595828187714, 9030.864537031983, 9072.77318067047, 9086.136817002374, 9097.090290970917, 8939.597718102701, 8971.0, 9020.458772473672, 9031.503267320295, 9034.86447703285, 42324.78837605823, 42603.573964260584, 42610.0, 42647.786761065996, 42667.14665706668, 3372.0, 3475.061733331986, 3569.7679650822615, 3809.980950095232, 3851.383778595411, 31585.0, 32088.197795055075, 32301.838490807397, 32386.0, 32503.187420314443, 4111.588841115894, 4336.501302350207, 4343.782810859444, 4501.7524036178, 4533.455985281755, 272718.0, 366553.0, 368859.15570422244, 369148.0, 371182.0, 56876.71561642165, 57242.0, 57385.42614573884, 57521.0, 57535.712321438965, 59841.700791495765, 59895.0, 59953.400465995655, 59964.70017649884, 59972.0, 16871.156442177842, 17150.0, 17153.571160721083, 17159.142042897805, 17164.39924602633, 13212.669683257995, 13316.933415332862, 13350.399232034582, 13363.599092027254, 13372.197668139943, 14448.711025779425, 15438.38246470151, 15477.5356739298, 15491.922540387226, 15701.685966280611, 5888.234529511136, 6015.819525414245, 6189.5126033625265, 6228.906566401508, 6278.434940855253, 918.7932715139041, 1022.3610243597751, 1063.3779239145188, 1076.3057827701089, 1107.9390633515234, 30933.69066309353, 31031.0, 31387.686123138934, 31943.0, 32091.839540802146, 904.4618452021168, 1099.4887377369562, 1132.3545579020044, 1160.5995931403652, 1182.2355905700042, 15694.450694225647, 16497.0, 16536.917315413346, 16623.418180363627, 16648.75026874598, 486505.0, 491991.0, 492400.0, 492823.0, 493382.0, 56978.0, 57395.13907291394, 57415.42584574184, 57419.42580574224, 58121.70939145277, 1204.5783975608642, 1270.1807334269529, 1313.1005261395974, 1345.387848528917, 1360.4286199796109, 27891.302717432227, 32342.514862277556, 32369.0, 32375.0, 32542.0, 47650.76174619105, 48284.27573586469, 48415.27377089417, 48422.27366589433, 48474.515254847, 386348.0, 387773.0, 388825.0, 389077.0, 389113.0, 26688.599671004562, 27197.59203611987, 27288.590671140348, 28298.292542685773, 39096.0, 46138.307925380475, 46879.76560117178, 46975.765121175544, 47035.52964470448, 47311.29033064576, 38577.61422385852, 39264.6073539261, 39590.108182446646, 40273.39589906095, 41753.373699393924, 39589.2082158361, 39829.40255896106, 40207.396889046104, 41172.588274116875, 41988.0, 39212.41181382225, 39732.0, 39891.20217595574, 40254.0, 40674.1865162701, 34310.65689343074, 35997.46003809893, 36989.26021479609, 37003.25993480169, 37082.0, 26831.597526037516, 27212.863935680194, 27331.86334068317, 27413.177604671095, 27638.170854874385], "value": 19715.643249941222, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.6534390345029, 387.57754048087577, 395.55895176877783, 401.24766063630693, 418.58769112424255, 13469.730605387886, 15222.0, 16894.155292235384, 19280.3251886184, 19857.503562410948, 17970.820291797078, 18264.543386415346, 18448.44654660359, 18519.351822686207, 19698.0, 5642.520385767215, 5860.238169038041, 5862.20860183874, 5863.560232982522, 5867.266591676041, 5454.10007348787, 5717.142428635696, 5762.538996880239, 5846.88306233875, 5848.800995795852, 5635.492805647491, 5835.679037652927, 5838.678872662002, 5844.094165404369, 5852.034414321632, 5752.568557358215, 5805.564582656297, 5832.0, 5859.6777177255235, 5866.6480011199465, 5602.691851948141, 5622.297212848394, 5699.316082070178, 5703.74333155009, 5781.624194427391, 13178.86821131786, 13348.866511334956, 13356.332183390792, 13410.0, 20519.281825136048, 2135.4251784060534, 2190.7809219078126, 2191.485001024758, 2203.8787866667326, 2229.175205174096, 9547.61809527618, 9681.806363872754, 9754.90245097547, 9885.950570247174, 9926.751831204205, 1735.8524525415355, 1812.6918423867842, 1886.4906475251605, 1922.3055782515069, 1930.3050901675385, 83238.0, 84675.5766221171, 85568.14431855726, 86521.56739216327, 86553.56723216406, 10829.945850270698, 10890.40102794338, 11016.834747478793, 11045.005949464587, 11343.376114313793, 15629.765553516707, 15653.530394088195, 15676.764848527282, 15687.529374118794, 15706.293216805043, 7877.448578599558, 8022.318102961197, 8305.667773289, 8523.74428767138, 8534.743957681156, 7876.212378762192, 7944.04671439431, 8010.47455441306, 8121.675132994732, 8168.183181681903, 4411.735295882258, 4490.595846373777, 4542.273236282245, 4735.12400205963, 4845.951540484621, 1925.9422217333502, 1944.4652720501692, 1956.4228552577024, 1989.2043182727025, 1999.2402886902885, 939.5490164720973, 947.6351604632266, 977.8973207813219, 986.5461887531673, 1017.1252722658508, 17322.307107715802, 20251.189952402034, 20593.897030514752, 20690.482737931674, 20839.166433342798, 978.9265805064649, 989.7228775942748, 998.6404894238068, 1000.099910080933, 1032.318669678024, 10443.268971171945, 10875.0, 10929.726756831144, 10933.671989840317, 10957.726056848642, 381787.0, 426024.0, 455461.0, 456237.0, 458121.0, 72612.27387726161, 81046.59476702697, 95331.52334238423, 96335.51832240794, 96787.0, 946.8343039967978, 999.1706883287, 1040.516159985595, 1043.3218408034838, 1054.8628678271775, 15906.443274485337, 17099.401520946707, 17108.743368849726, 17421.738673920154, 18368.101716262136, 40168.196636067696, 43473.782631086644, 43686.0, 44814.10371792611, 44913.77543112394, 52707.0, 58240.417595825194, 58466.4153358478, 59120.11319830292, 59827.102593462005, 7992.5204487730925, 8333.749987500385, 8425.831483370175, 8462.534560599226, 8487.0, 12099.879001209874, 13084.542041028519, 13096.607101786962, 13184.274864882524, 13191.406386712813, 11056.004959553678, 11083.612073577386, 11245.550177993116, 11535.077193824642, 11992.160548761503, 10948.945255273673, 11450.94274528622, 11600.419979001184, 11661.24201926872, 12159.513619455123, 10442.530086146293, 10724.839127413252, 10788.406637635006, 10825.67522974312, 11225.438728063727, 7901.762947111596, 8237.629306681094, 8421.62102705392, 8442.197991190827, 8715.433496822687, 8827.735167944971, 11066.944665276622, 11232.438378081226, 11425.771484570429, 12058.0], "value": 8975.954990008842, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1452.1069542231523, 1493.8954273200877, 1635.501172142496, 1766.266999195334, 1779.777527809024, 7657.693692252317, 7933.968584084061, 8186.590670466475, 8815.867761983567, 8817.735467935965, 8190.467619604721, 8477.32181425486, 8519.616617252223, 8692.608832602533, 8705.347098967586, 2375.3586531636447, 2400.291913885408, 2401.0395841663385, 2401.315625046862, 2402.147237730598, 2006.257684656679, 2392.270357540949, 2394.078279862248, 2442.31615147758, 2444.168982545934, 2089.0, 2305.1816605105196, 2394.892229849661, 2398.928032159038, 2413.5293617744546, 2322.0479603362623, 2334.323046316566, 2334.789868911789, 2341.461463863321, 2379.3694670912223, 2290.335802617239, 2341.9297421077395, 2342.5900467418305, 2346.23747282134, 2355.0462062864567, 8578.82842343156, 8635.222829945335, 8674.219320261207, 8730.738077857612, 8731.0, 5054.848354549369, 5177.559907407875, 5183.0, 5189.455107213764, 5193.506616871391, 3247.0, 3374.7300215982664, 3420.9486857696907, 3436.8281585920854, 3484.4599087141532, 1117.1844553475926, 1132.0830127596673, 1185.2769810415639, 1186.4186548591215, 1196.0252394298707, 11950.402479875971, 11991.0, 12020.45907934145, 12044.217125886702, 12052.819207711891, 4343.587359200871, 4551.975805443749, 4577.198990176772, 4646.697964632288, 4691.061787642485, 10189.490525473844, 10189.745256368502, 10193.74515637115, 10194.847077293847, 10202.132818710345, 5222.556082732935, 5222.634415590872, 5230.503102205284, 5236.764345604457, 5269.314989051399, 4642.651801114863, 4742.430908291027, 4768.57082862544, 4804.759762011886, 4809.44691360491, 3898.083950271684, 3926.489556357655, 3947.1908258806875, 4019.070845993939, 4058.370952502367, 1135.4720055174384, 1167.754771497978, 1294.0812023463448, 1296.8508621508458, 1331.640457076584, 900.8918929728475, 918.1186061381015, 960.5053397500293, 962.485070487286, 965.9275554333453, 5742.7702891883855, 5910.704464776744, 5971.790987315423, 6161.087709304624, 6197.690115494207, 975.0834215837091, 981.5877331520778, 987.521052289639, 1022.1516141602452, 1030.9587616495405, 4051.6150965658803, 4155.376693495939, 4258.6805989550285, 4348.630366418826, 4362.276900624299, 10404.58381664725, 10660.520276587573, 10730.624428144978, 10736.248462607698, 10813.891861081445, 10601.57593696259, 10602.469876506144, 10610.734731631772, 10615.150787936947, 10619.36283822973, 7620.504667196615, 7735.303822655988, 7808.726694565663, 7980.840383192303, 7981.563318602591, 5864.648121112747, 5910.320313163869, 5994.67029313392, 6050.273967123973, 6066.241719785027, 9707.320487565803, 9804.1666458349, 9815.85276220843, 9851.113399794052, 9854.113129818064, 9670.854937175807, 9905.801883962424, 9952.054554817281, 10146.391216527032, 10151.695449136538, 7610.315071643578, 7723.729669461766, 7772.883406749016, 7776.88334674969, 7780.533168009937, 8450.873236901574, 8456.619452124545, 8477.109903460048, 8511.74464766058, 8521.06268310473, 7296.598687072262, 7437.628118593941, 7540.924590754023, 7600.201978792147, 7634.4655874088285, 7162.677679504539, 7294.817629559091, 7340.376068034277, 7422.183559808309, 7505.986691796537, 6957.547759395623, 7047.436205103477, 7047.929520704727, 7051.1186101737285, 7071.116110486189, 5155.639105262596, 5216.817411390583, 5280.841574752763, 5312.574994000548, 5532.668039917457, 5285.735713214247, 5313.2561441397465, 5329.387120481194, 5361.946380536145, 5559.722013899208], "value": 4626.188610150817, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.8836077208344, 291.2253405940199, 295.13672507914345, 295.7397490208616, 303.7084398976982, 5041.798328066882, 5188.221766734982, 5196.402413722419, 5230.63242192769, 5261.421243663194, 5019.949800501994, 5122.92315615266, 5124.590032797368, 5127.33344665193, 5145.691258524491, 4037.777922214277, 4038.7778672173017, 4039.313316736155, 4048.1296521247928, 4053.412255222991, 3692.7968961707097, 3803.904902377435, 4047.6845025366724, 4055.2903241932545, 4062.9187416251657, 3924.4309575111733, 3942.5860284669984, 3960.2673505401594, 3963.8612648557305, 3964.484616999786, 3918.686505079587, 3928.666063384616, 3938.113924367023, 3945.506811648544, 3949.4794504115835, 3433.969809057273, 3438.381091403546, 3441.587009558844, 3443.500692399588, 3454.4990976308545, 4729.456112547033, 4957.454679985199, 5005.274235235906, 5024.346834911438, 5036.194208926554, 4521.480029796583, 4533.9319910201375, 4555.63554915606, 4563.77181140945, 4565.61192298655, 5407.3781515125875, 5411.756470958777, 5424.403315635277, 5424.403315635316, 5446.564274858002, 3644.2529281497227, 3816.5992570779945, 3817.48463957365, 3848.8652897148745, 3854.5759966403675, 5503.147012213113, 5550.47270509301, 5551.555875529948, 5555.444455554452, 5560.138178582326, 3933.488646475997, 3942.42834788954, 3944.1322908959983, 3947.2697550953167, 3956.4263181839046, 3889.844406223776, 3949.269385163754, 3978.1844721831953, 3992.2215168042535, 4006.1386801837743, 4522.683412161183, 4531.864044078683, 4537.954620453819, 4542.545745425497, 4542.772861356919, 5376.65583604099, 5378.78484860609, 5391.16436952273, 5411.377691565521, 5422.972885135628, 1297.195738642048, 1317.9209247445183, 1360.9863901361057, 1380.171896861895, 1393.965150871216, 861.9353548483764, 878.7846977490556, 890.2077151335317, 892.3352102683542, 902.9774255643662, 4631.467381251199, 4638.512956139624, 4645.279981602857, 4653.720776753338, 4690.601298889633, 4835.903281934412, 4874.36633237684, 4884.65807393479, 4904.239842824368, 4918.6556941014505, 4645.46577143626, 4657.394538709946, 4658.58072773452, 4660.347551342815, 4711.303930584987, 2736.644236249315, 2934.2224310557717, 2945.4550908082074, 2952.4647183464476, 2954.896578619738, 5746.678263999303, 5772.855678608068, 5793.449622285876, 5813.157092221604, 5813.331466881363, 5547.639403438763, 5683.715814209273, 5711.971440142773, 5722.971385143131, 5732.254806875079, 5772.797952071657, 5782.103773915134, 5782.306123265235, 5790.420957904176, 5790.739416726257, 5545.390007099135, 5560.694161820977, 5562.972185139049, 5567.109262517978, 5571.275734154534, 5295.676080979755, 5483.437220392169, 5500.697461639648, 5522.751476183662, 5524.75138618755, 5778.16216648592, 5783.265020493919, 5789.1474728086605, 5796.362400136066, 5811.157382179645, 3842.884713458601, 3843.7117216208335, 3856.710746694065, 3860.575336712903, 3862.9806850965565, 5279.419263880894, 5324.7220667038855, 5595.216669666169, 5612.887742245214, 5621.409751975983, 5298.953101966307, 5376.435474275301, 5474.20815384149, 5478.287822583198, 5489.807856724996, 5413.485718856702, 5424.457554244544, 5444.401115877171, 5446.319210098738, 5474.753636086465, 5276.649758777481, 5399.271098401822, 5407.405185429679, 5413.132469298086, 5439.945600543944, 5399.433059528693, 5459.2629994950175, 5459.399466058651, 5468.972655136698, 5478.506934375925, 5307.52232299095, 5329.62692611529, 5346.021971870433, 5367.302250707383, 5371.597130215171], "value": 4057.4154855234638, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.2821066091266, 728.4136270302405, 751.2750196060801, 768.5849641193755, 769.9836216194625, 14365.35355908984, 15450.61373465664, 15521.611959700986, 15541.067535947837, 15560.922195389021, 15412.61468463289, 15558.299876505556, 15575.688486230269, 15583.142927139002, 15585.454509092184, 660.7951535024134, 679.8776220280348, 775.3642013548892, 778.4550814429894, 798.3613109512396, 793.714262865368, 811.0429692962302, 812.6627449608405, 812.9105798362175, 813.2680587471264, 858.7252079334615, 865.709987154304, 865.8268346330727, 865.8441480533502, 866.2463656618751, 683.193831279088, 716.5521549031855, 794.9006374203225, 850.9872351914727, 852.7186028610544, 618.3322012226785, 621.8631900981777, 622.4118208293165, 622.5735371270694, 628.1990462160782, 15338.769918451233, 15359.078455292718, 15362.385504579803, 15371.385144594204, 15377.538673839803, 14187.43250269988, 14202.928985355007, 14437.0, 14530.12819230839, 14566.563003109924, 11839.971345279437, 11978.880211197864, 12309.507619695201, 12463.252204867737, 12796.168249063874, 4190.455240818674, 4369.366441865912, 4396.20868243716, 4538.818447262105, 4539.591436770706, 79086.60456697794, 79540.0, 79631.0, 80702.19297807064, 80730.59634701788, 10149.543270552844, 10270.178385729123, 10392.844107338396, 10393.53229104692, 10418.635347762793, 20891.37325880226, 20953.47616309574, 20981.370558882954, 21064.0, 21401.0, 6964.93035069653, 7586.848263034708, 7669.808254793563, 7696.230376962259, 7821.569813660303, 3891.124025232228, 4322.589354011427, 4338.349247612883, 4348.391225228471, 4387.14450682114, 3202.59967504062, 3300.603927528712, 3307.818070006125, 3315.4032274190395, 3321.8339083046235, 542.0432935868196, 557.4481263549058, 559.5439716630933, 559.6390328238264, 561.7331767410448, 514.0, 600.7506884642912, 636.3318515558645, 684.5174152222671, 708.9255628159073, 8652.3510736695, 9140.268778497704, 9537.523123843781, 9857.162141217934, 9859.556319965619, 611.5230120506012, 656.917885264342, 670.3196255800367, 690.975815846453, 728.8287252495659, 4359.607635312837, 4629.699069560468, 4790.233562629963, 4791.760411979387, 4798.4001999750035, 93128.0, 94537.52731236437, 95223.52388238015, 128827.0, 132745.0, 93441.0, 94245.0, 94429.52785236167, 94930.0, 95539.52230238804, 53322.20016699753, 53455.73272133692, 82232.58883705662, 84156.27265228561, 85753.57123214469, 10123.797524049625, 10230.084425938572, 10401.53193106297, 10477.52851121686, 10569.524371403151, 44048.779756101016, 46541.30188047114, 51968.740156298976, 53470.0, 55768.16347754706, 22128.33614991553, 43139.784301078296, 72190.0, 76164.61917690375, 80310.0, 12005.459754311252, 12080.637580872588, 12177.939110304393, 12337.876621233672, 12509.56216532417, 14577.562673119823, 14619.415223390944, 14666.85333146655, 14699.706005880036, 14717.852821472077, 13600.65998350049, 13653.863461365656, 13819.585412437644, 14200.928995354958, 14835.332410041357, 13605.387757550736, 13606.795898061338, 14204.644883877985, 14284.357203926056, 15388.307526161127, 12660.493580256687, 12860.485580576673, 13265.535706250235, 13354.198748075147, 13384.0, 9419.397919978373, 9617.855732164164, 9688.176504997155, 9690.903090969283, 9892.060254275832, 9520.190783783455, 10181.037281194715, 10396.948015259875, 10474.476276186313, 10687.0], "value": 7275.498394899241, "unit": "score"}], "name": "dromaeo_css", "value": 7065.330171141993, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 110.0, 110.0, 111.0, 111.0, 111.0, 111.0, 111.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 120.0, 124.0, 157.0, 119.0, 118.0, 118.0, 126.0, 152.0, 120.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 163.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 110.0, 77.0, 77.0, 76.0, 86.0, 109.0, 76.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 126.0, 82.0, 81.0, 82.0, 105.0, 81.0, 82.0, 110.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 145.0, 145.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 51.0, 50.0, 52.0, 52.0, 53.0, 53.0, 52.0, 53.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 86.0, 80.0, 77.0, 82.0, 77.0, 86.0, 77.0, 77.0, 78.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 124.0, 125.0, 123.0, 123.0, 124.0, 135.0, 138.0, 180.0, 144.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 163.0, 165.0, 164.0, 172.0, 164.0, 167.0, 165.0, 163.0, 167.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 66.0, 65.0, 69.0, 65.0, 67.0, 65.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1496.6, "alertThreshold": 2.0}]} 14:38:45 INFO - Return code: 0 14:38:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:38:46 INFO - # TBPL SUCCESS # 14:38:46 INFO - Running post-action listener: _resource_record_post_action 14:38:46 INFO - [mozharness: 2016-09-16 21:38:46.026379Z] Finished run-tests step (success) 14:38:46 INFO - Running post-run listener: _resource_record_post_run 14:38:46 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401072128; Read time: 20; Write time: 259172 14:38:46 INFO - TinderboxPrint: CPU usage
13.1% 14:38:46 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 14:38:46 INFO - TinderboxPrint: I/O write bytes / time
401,072,128 / 259,172 14:38:46 INFO - TinderboxPrint: CPU idle
8,291.6 (86.9%) 14:38:46 INFO - TinderboxPrint: CPU user
1,196.5 (12.5%) 14:38:46 INFO - TinderboxPrint: Swap in / out
0 / 0 14:38:46 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 103809024; Read time: 0; Write time: 78604 14:38:46 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 297263104; Read time: 20; Write time: 180568 14:38:46 INFO - Running post-run listener: _upload_blobber_files 14:38:46 INFO - Blob upload gear active. 14:38:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:38:46 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 14:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:38:47 INFO - (blobuploader) - INFO - Open directory for files ... 14:38:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:38:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:38:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:48 INFO - (blobuploader) - INFO - Done attempting. 14:38:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:38:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:38:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:52 INFO - (blobuploader) - INFO - Done attempting. 14:38:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:38:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:38:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:52 INFO - (blobuploader) - INFO - Done attempting. 14:38:52 INFO - (blobuploader) - INFO - Iteration through files over. 14:38:52 INFO - Return code: 0 14:38:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:38:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:38:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ea975ba91cf58da7e7e11a4c9a86219b8f3f3b31ba36a9984ac7bd94d36f728d7d607c9d00bcb9f7a02c85956f8c39f56429d020c6f5cb983f5af51fbd50ec5d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fc7b6c562edd524044e1f3163b803b9fc87a1d3629cfb6ffb0e4f77afb5b736c57a9b7767d58de89efd5c4a0ffa365e03420f8f0c1803cb5da1510334f87bc1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cdea96e31d06b02e1afb70e103e16d9c274e7ce7afe168073aa1a57580a61bb7d94a64e3a2cd667a0ef2d4443775bfb1665291f94ba53925d506adaf80df8445"} 14:38:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:38:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:38:52 INFO - Contents: 14:38:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ea975ba91cf58da7e7e11a4c9a86219b8f3f3b31ba36a9984ac7bd94d36f728d7d607c9d00bcb9f7a02c85956f8c39f56429d020c6f5cb983f5af51fbd50ec5d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fc7b6c562edd524044e1f3163b803b9fc87a1d3629cfb6ffb0e4f77afb5b736c57a9b7767d58de89efd5c4a0ffa365e03420f8f0c1803cb5da1510334f87bc1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cdea96e31d06b02e1afb70e103e16d9c274e7ce7afe168073aa1a57580a61bb7d94a64e3a2cd667a0ef2d4443775bfb1665291f94ba53925d506adaf80df8445"} 14:38:52 INFO - Running post-run listener: copy_logs_to_upload_dir 14:38:52 INFO - Copying logs to upload dir... 14:38:52 INFO - mkdir: /builds/slave/test/build/upload/logs 14:38:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.265672 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-09-16 14:38:52.399829) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 14:38:52.403055) ========= 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=cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ea975ba91cf58da7e7e11a4c9a86219b8f3f3b31ba36a9984ac7bd94d36f728d7d607c9d00bcb9f7a02c85956f8c39f56429d020c6f5cb983f5af51fbd50ec5d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fc7b6c562edd524044e1f3163b803b9fc87a1d3629cfb6ffb0e4f77afb5b736c57a9b7767d58de89efd5c4a0ffa365e03420f8f0c1803cb5da1510334f87bc1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cdea96e31d06b02e1afb70e103e16d9c274e7ce7afe168073aa1a57580a61bb7d94a64e3a2cd667a0ef2d4443775bfb1665291f94ba53925d506adaf80df8445"} build_url:https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011357 build_url: 'https://queue.taskcluster.net/v1/task/GfyppdUtQBKXwxmN_CrWrw/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/sha512/ea975ba91cf58da7e7e11a4c9a86219b8f3f3b31ba36a9984ac7bd94d36f728d7d607c9d00bcb9f7a02c85956f8c39f56429d020c6f5cb983f5af51fbd50ec5d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fc7b6c562edd524044e1f3163b803b9fc87a1d3629cfb6ffb0e4f77afb5b736c57a9b7767d58de89efd5c4a0ffa365e03420f8f0c1803cb5da1510334f87bc1d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cdea96e31d06b02e1afb70e103e16d9c274e7ce7afe168073aa1a57580a61bb7d94a64e3a2cd667a0ef2d4443775bfb1665291f94ba53925d506adaf80df8445"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 14:38:52.428622) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:38:52.428972) ========= 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=cbe409c6de289db6ac707de3000001d4-1474060613.436014-852678508 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 14:38:52.514506) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 14:38:52.514838) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 14:38:52.515166) ========= ========= Total master_lag: 0.20 =========