builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-026 starttime: 1489419764.48 results: success (0) revision: 7b19a63862252ffb89bfe1ba79724e76e20fb6f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.479320) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.479744) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.480030) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.494027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.494302) ========= 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=41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-13 08:42:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.4M=0s 2017-03-13 08:42:45 (40.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144393 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.652250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.652557) ========= 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=41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019436 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.701767) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:44.702115) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7b19a63862252ffb89bfe1ba79724e76e20fb6f4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 7b19a63862252ffb89bfe1ba79724e76e20fb6f4 --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=41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-13 08:42:45,414 truncating revision to first 12 chars 2017-03-13 08:42:45,414 Setting DEBUG logging. 2017-03-13 08:42:45,414 attempt 1/10 2017-03-13 08:42:45,414 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7b19a6386225?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-13 08:42:45,746 unpacking tar archive at: mozilla-central-7b19a6386225/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642525 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:45.357548) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:45.357864) ========= script_repo_revision: 7b19a63862252ffb89bfe1ba79724e76e20fb6f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:45.358235) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:45.358493) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-13 08:42:45.365864) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-13 08:42:45.366169) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:42:46 INFO - MultiFileLogger online at 20170313 08:42:46 in /builds/slave/test 08:42:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:42:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:42:46 INFO - {'append_to_log': False, 08:42:46 INFO - 'base_work_dir': '/builds/slave/test', 08:42:46 INFO - 'blob_upload_branch': 'Firefox', 08:42:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:42:46 INFO - 'branch': 'Firefox', 08:42:46 INFO - 'buildbot_json_path': 'buildprops.json', 08:42:46 INFO - 'config_files': ('talos/linux_config.py',), 08:42:46 INFO - 'default_actions': ('clobber', 08:42:46 INFO - 'read-buildbot-config', 08:42:46 INFO - 'download-and-extract', 08:42:46 INFO - 'populate-webroot', 08:42:46 INFO - 'create-virtualenv', 08:42:46 INFO - 'install', 08:42:46 INFO - 'run-tests'), 08:42:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:42:46 INFO - 'download_minidump_stackwalk': True, 08:42:46 INFO - 'download_symbols': 'ondemand', 08:42:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:42:46 INFO - 'tooltool.py': '/tools/tooltool.py', 08:42:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:42:46 INFO - '/tools/misc-python/virtualenv.py')}, 08:42:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:42:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:42:46 INFO - 'gecko_profile': False, 08:42:46 INFO - 'gecko_profile_interval': 0, 08:42:46 INFO - 'installer_path': 'installer.exe', 08:42:46 INFO - 'log_level': 'info', 08:42:46 INFO - 'log_name': 'talos', 08:42:46 INFO - 'log_to_console': True, 08:42:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:42:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:42:46 INFO - 'opt_config_files': (), 08:42:46 INFO - 'pip_index': False, 08:42:46 INFO - 'suite': 'dromaeojs', 08:42:46 INFO - 'system_bits': '32', 08:42:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:42:46 INFO - 'title': 'talos-linux64-ix-026', 08:42:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:42:46 INFO - 'use_talos_json': True, 08:42:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:42:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:42:46 INFO - 'work_dir': 'build'} 08:42:46 INFO - [mozharness: 2017-03-13 15:42:46.175214Z] Running clobber step. 08:42:46 INFO - Running pre-action listener: _resource_record_pre_action 08:42:46 INFO - Running main action method: clobber 08:42:46 INFO - rmtree: /builds/slave/test/build 08:42:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:42:47 INFO - Running post-action listener: _resource_record_post_action 08:42:47 INFO - [mozharness: 2017-03-13 15:42:47.771849Z] Finished clobber step (success) 08:42:47 INFO - [mozharness: 2017-03-13 15:42:47.771950Z] Running read-buildbot-config step. 08:42:47 INFO - Running pre-action listener: _resource_record_pre_action 08:42:47 INFO - Running main action method: read_buildbot_config 08:42:47 INFO - Using buildbot properties: 08:42:47 INFO - { 08:42:47 INFO - "project": "", 08:42:47 INFO - "product": "firefox", 08:42:47 INFO - "who": "ffxbld@noreply.mozilla.org", 08:42:47 INFO - "installer_path": "public/build/target.tar.bz2", 08:42:47 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:42:47 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:42:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:42:47 INFO - "stage_platform": "linux64-pgo", 08:42:47 INFO - "basedir": "/builds/slave/test", 08:42:47 INFO - "buildnumber": 73, 08:42:47 INFO - "platform": "ubuntu64_hw", 08:42:47 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:42:47 INFO - "slavebuilddir": "test", 08:42:47 INFO - "taskId": "Q4o5QWF6S7WnzggLwA4HFw", 08:42:47 INFO - "branch": "mozilla-central", 08:42:47 INFO - "script_repo_revision": "production", 08:42:47 INFO - "revision": "7b19a63862252ffb89bfe1ba79724e76e20fb6f4", 08:42:47 INFO - "slavename": "talos-linux64-ix-026", 08:42:47 INFO - "repo_path": "mozilla-central" 08:42:47 INFO - } 08:42:47 INFO - Finding installer, test and symbols from parent task. 08:42:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q4o5QWF6S7WnzggLwA4HFw'}, attempt #1 08:42:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q4o5QWF6S7WnzggLwA4HFw'}, attempt #1 08:42:48 INFO - Task dependencies: b7YI6CuUSbKJJTmiSxG3Og 08:42:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og'}, attempt #1 08:42:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.tar.bz2 08:42:49 INFO - Set test_url: https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/test_packages.json 08:42:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.crashreporter-symbols.zip 08:42:49 INFO - Running post-action listener: _resource_record_post_action 08:42:49 INFO - [mozharness: 2017-03-13 15:42:49.204050Z] Finished read-buildbot-config step (success) 08:42:49 INFO - [mozharness: 2017-03-13 15:42:49.204268Z] Running download-and-extract step. 08:42:49 INFO - Running pre-action listener: _resource_record_pre_action 08:42:49 INFO - Running main action method: download_and_extract 08:42:49 INFO - mkdir: /builds/slave/test/build/tests 08:42:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:49 INFO - trying https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.test_packages.json 08:42:49 INFO - Downloading https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:42:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:42:49 INFO - Downloaded 896 bytes. 08:42:49 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:42:49 INFO - Using the following test package requirements: 08:42:49 INFO - {u'common': [u'target.common.tests.zip'], 08:42:49 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:42:49 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:42:49 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:42:49 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:42:49 INFO - u'mozbase': [u'target.common.tests.zip'], 08:42:49 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:42:49 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:42:49 INFO - u'web-platform': [u'target.common.tests.zip', 08:42:49 INFO - u'target.web-platform.tests.zip'], 08:42:49 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:42:49 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:42:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.common.tests.zip 08:42:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:42:49 INFO - Fetch https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.common.tests.zip into memory 08:42:50 INFO - Expected file size: 60306114 08:42:50 INFO - Obtained file size: 60306114 08:43:00 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:43:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.common.tests.zip 08:43:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:43:00 INFO - Fetch https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.common.tests.zip into memory 08:43:01 INFO - Expected file size: 60306114 08:43:01 INFO - Obtained file size: 60306114 08:43:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.talos.tests.zip 08:43:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:43:12 INFO - Fetch https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.talos.tests.zip into memory 08:43:13 INFO - Expected file size: 14404438 08:43:13 INFO - Obtained file size: 14404438 08:43:13 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')]} 08:43:13 INFO - trying https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.tar.bz2 08:43:13 INFO - Downloading https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:43:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:43:14 INFO - Downloaded 67042725 bytes. 08:43:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.tar.bz2 08:43:14 INFO - mkdir: /builds/slave/test/properties 08:43:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:43:14 INFO - Writing to file /builds/slave/test/properties/build_url 08:43:14 INFO - Contents: 08:43:14 INFO - build_url:https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.tar.bz2 08:43:14 INFO - Running post-action listener: _resource_record_post_action 08:43:14 INFO - Running post-action listener: set_extra_try_arguments 08:43:14 INFO - [mozharness: 2017-03-13 15:43:14.937474Z] Finished download-and-extract step (success) 08:43:14 INFO - [mozharness: 2017-03-13 15:43:14.937571Z] Running populate-webroot step. 08:43:14 INFO - Running pre-action listener: _resource_record_pre_action 08:43:14 INFO - Running main action method: populate_webroot 08:43:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:43:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:43:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:43:14 INFO - '--tppagecycles', 08:43:14 INFO - '7'], 08:43:14 INFO - 'tests': ['tsvgm']}}, 08:43:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:43:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:43:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:43:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:43:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:43:14 INFO - 'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:43:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:43:14 INFO - 'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['damp', 'tps']}, 08:43:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:43:14 INFO - 'tests': ['damp', 'tps']}, 08:43:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['dromaeo_dom']}, 08:43:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:43:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['basic_compositor_video']}, 08:43:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:43:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['a11yr', 08:43:14 INFO - 'ts_paint', 08:43:14 INFO - 'tpaint', 08:43:14 INFO - 'sessionrestore', 08:43:14 INFO - 'sessionrestore_no_auto_restore', 08:43:14 INFO - 'tabpaint']}, 08:43:14 INFO - 'other-e10s': {'tests': ['a11yr', 08:43:14 INFO - 'ts_paint', 08:43:14 INFO - 'tpaint', 08:43:14 INFO - 'sessionrestore', 08:43:14 INFO - 'sessionrestore_no_auto_restore', 08:43:14 INFO - 'tabpaint']}, 08:43:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:43:14 INFO - 'ts_paint', 08:43:14 INFO - 'tpaint', 08:43:14 INFO - 'sessionrestore', 08:43:14 INFO - 'sessionrestore_no_auto_restore', 08:43:14 INFO - 'tabpaint']}, 08:43:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:43:14 INFO - 'ts_paint', 08:43:14 INFO - 'tpaint', 08:43:14 INFO - 'sessionrestore', 08:43:14 INFO - 'sessionrestore_no_auto_restore', 08:43:14 INFO - 'tabpaint']}, 08:43:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['a11yr', 08:43:14 INFO - 'ts_paint', 08:43:14 INFO - 'tpaint', 08:43:14 INFO - 'sessionrestore', 08:43:14 INFO - 'sessionrestore_no_auto_restore', 08:43:14 INFO - 'tabpaint']}, 08:43:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['a11yr', 08:43:14 INFO - 'ts_paint', 08:43:14 INFO - 'tpaint', 08:43:14 INFO - 'sessionrestore', 08:43:14 INFO - 'sessionrestore_no_auto_restore', 08:43:14 INFO - 'tabpaint']}, 08:43:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['tsvgx', 08:43:14 INFO - 'tsvgr_opacity', 08:43:14 INFO - 'tart', 08:43:14 INFO - 'tscrollx', 08:43:14 INFO - 'cart', 08:43:14 INFO - 'tsvg_static']}, 08:43:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:43:14 INFO - 'tsvgr_opacity', 08:43:14 INFO - 'tart', 08:43:14 INFO - 'tscrollx', 08:43:14 INFO - 'cart', 08:43:14 INFO - 'tsvg_static']}, 08:43:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:43:14 INFO - 'talos_options': ['--disable-e10s'], 08:43:14 INFO - 'tests': ['tp5o']}, 08:43:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:43:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:43:14 INFO - 'talos_options': ['--disable-e10s', 08:43:14 INFO - '--xperf_path', 08:43:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:14 INFO - 'tests': ['tp5n']}, 08:43:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:43:14 INFO - 'talos_options': ['--xperf_path', 08:43:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:14 INFO - 'tests': ['tp5n']}}, 08:43:14 INFO - 'talos.zip': {'path': '', 08:43:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:43:14 INFO - Running post-action listener: _resource_record_post_action 08:43:14 INFO - [mozharness: 2017-03-13 15:43:14.952164Z] Finished populate-webroot step (success) 08:43:14 INFO - [mozharness: 2017-03-13 15:43:14.952259Z] Running create-virtualenv step. 08:43:14 INFO - Running pre-action listener: _resource_record_pre_action 08:43:14 INFO - Running main action method: create_virtualenv 08:43:14 INFO - Creating virtualenv /builds/slave/test/build/venv 08:43:14 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 08:43:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:43:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:43:15 INFO - Using real prefix '/usr' 08:43:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:43:15 INFO - Installing distribute.............................................................................................................................................................................................done. 08:43:17 INFO - Installing pip.................done. 08:43:17 INFO - Return code: 0 08:43:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:43:17 INFO - Reading from file tmpfile_stdout 08:43:17 INFO - Output received: 08:43:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:43:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:43:17 INFO - Using env: {'DISPLAY': ':0', 08:43:17 INFO - 'HOME': '/home/cltbld', 08:43:17 INFO - 'LANG': 'en_US.UTF-8', 08:43:17 INFO - 'LANGUAGE': 'en_US:en', 08:43:17 INFO - 'LOGNAME': 'cltbld', 08:43:17 INFO - 'MAIL': '/var/mail/cltbld', 08:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:17 INFO - 'MOZ_NO_REMOTE': '1', 08:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:17 INFO - 'NO_EM_RESTART': '1', 08:43:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:17 INFO - 'PWD': '/builds/slave/test', 08:43:17 INFO - 'SHELL': '/bin/bash', 08:43:17 INFO - 'SHLVL': '1', 08:43:17 INFO - 'TERM': 'linux', 08:43:17 INFO - 'TMOUT': '86400', 08:43:17 INFO - 'USER': 'cltbld', 08:43:17 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:17 INFO - '_': '/tools/buildbot/bin/python'} 08:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:17 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 08:43:17 INFO - Cleaning up... 08:43:17 INFO - Return code: 0 08:43:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:43:17 INFO - Using env: {'DISPLAY': ':0', 08:43:17 INFO - 'HOME': '/home/cltbld', 08:43:17 INFO - 'LANG': 'en_US.UTF-8', 08:43:17 INFO - 'LANGUAGE': 'en_US:en', 08:43:17 INFO - 'LOGNAME': 'cltbld', 08:43:17 INFO - 'MAIL': '/var/mail/cltbld', 08:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:17 INFO - 'MOZ_NO_REMOTE': '1', 08:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:17 INFO - 'NO_EM_RESTART': '1', 08:43:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:17 INFO - 'PWD': '/builds/slave/test', 08:43:17 INFO - 'SHELL': '/bin/bash', 08:43:17 INFO - 'SHLVL': '1', 08:43:17 INFO - 'TERM': 'linux', 08:43:17 INFO - 'TMOUT': '86400', 08:43:17 INFO - 'USER': 'cltbld', 08:43:17 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:17 INFO - '_': '/tools/buildbot/bin/python'} 08:43:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:18 INFO - Downloading/unpacking psutil>=3.1.1 08:43:18 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 08:43:18 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 08:43:18 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 08:43:18 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 08:43:18 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 08:43:18 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 08:43:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:43:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:20 INFO - Installing collected packages: psutil 08:43:20 INFO - Running setup.py install for psutil 08:43:20 INFO - building 'psutil._psutil_linux' extension 08:43:20 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 08:43:20 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 08:43:20 INFO - building 'psutil._psutil_posix' extension 08:43:20 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 08:43:20 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 08:43:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:20 INFO - Successfully installed psutil 08:43:20 INFO - Cleaning up... 08:43:20 INFO - Return code: 0 08:43:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:20 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:20 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 08:43:20 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 08:43:20 INFO - Using env: {'DISPLAY': ':0', 08:43:20 INFO - 'HOME': '/home/cltbld', 08:43:20 INFO - 'LANG': 'en_US.UTF-8', 08:43:20 INFO - 'LANGUAGE': 'en_US:en', 08:43:20 INFO - 'LOGNAME': 'cltbld', 08:43:20 INFO - 'MAIL': '/var/mail/cltbld', 08:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:20 INFO - 'MOZ_NO_REMOTE': '1', 08:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:20 INFO - 'NO_EM_RESTART': '1', 08:43:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:20 INFO - 'PWD': '/builds/slave/test', 08:43:20 INFO - 'SHELL': '/bin/bash', 08:43:20 INFO - 'SHLVL': '1', 08:43:20 INFO - 'TERM': 'linux', 08:43:20 INFO - 'TMOUT': '86400', 08:43:20 INFO - 'USER': 'cltbld', 08:43:20 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:20 INFO - '_': '/tools/buildbot/bin/python'} 08:43:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:43:21 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 08:43:21 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 08:43:21 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 08:43:21 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 08:43:21 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 08:43:21 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 08:43:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:43:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:43:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:43:23 INFO - Installing collected packages: mozsystemmonitor 08:43:23 INFO - Running setup.py install for mozsystemmonitor 08:43:23 INFO - Successfully installed mozsystemmonitor 08:43:23 INFO - Cleaning up... 08:43:23 INFO - Return code: 0 08:43:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:43:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:43:23 INFO - Using env: {'DISPLAY': ':0', 08:43:23 INFO - 'HOME': '/home/cltbld', 08:43:23 INFO - 'LANG': 'en_US.UTF-8', 08:43:23 INFO - 'LANGUAGE': 'en_US:en', 08:43:23 INFO - 'LOGNAME': 'cltbld', 08:43:23 INFO - 'MAIL': '/var/mail/cltbld', 08:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:23 INFO - 'MOZ_NO_REMOTE': '1', 08:43:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:23 INFO - 'NO_EM_RESTART': '1', 08:43:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:23 INFO - 'PWD': '/builds/slave/test', 08:43:23 INFO - 'SHELL': '/bin/bash', 08:43:23 INFO - 'SHLVL': '1', 08:43:23 INFO - 'TERM': 'linux', 08:43:23 INFO - 'TMOUT': '86400', 08:43:23 INFO - 'USER': 'cltbld', 08:43:23 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:23 INFO - '_': '/tools/buildbot/bin/python'} 08:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:23 INFO - Downloading/unpacking jsonschema==2.5.1 08:43:23 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 08:43:23 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 08:43:23 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 08:43:23 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 08:43:23 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 08:43:23 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 08:43:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:43:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:43:25 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 08:43:25 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 08:43:25 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 08:43:25 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 08:43:25 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 08:43:25 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 08:43:25 INFO - Downloading functools32-3.2.3-2.tar.gz 08:43:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:43:25 INFO - warning: no files found matching '*.txt' 08:43:25 INFO - no previously-included directories found matching 'build' 08:43:25 INFO - no previously-included directories found matching 'dist' 08:43:25 INFO - no previously-included directories found matching '.git*' 08:43:25 INFO - Installing collected packages: jsonschema, functools32 08:43:25 INFO - Running setup.py install for functools32 08:43:25 INFO - warning: no files found matching '*.txt' 08:43:25 INFO - no previously-included directories found matching 'build' 08:43:25 INFO - no previously-included directories found matching 'dist' 08:43:25 INFO - no previously-included directories found matching '.git*' 08:43:25 INFO - Successfully installed jsonschema functools32 08:43:25 INFO - Cleaning up... 08:43:26 INFO - Return code: 0 08:43:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:26 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:26 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 08:43:26 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 08:43:26 INFO - Using env: {'DISPLAY': ':0', 08:43:26 INFO - 'HOME': '/home/cltbld', 08:43:26 INFO - 'LANG': 'en_US.UTF-8', 08:43:26 INFO - 'LANGUAGE': 'en_US:en', 08:43:26 INFO - 'LOGNAME': 'cltbld', 08:43:26 INFO - 'MAIL': '/var/mail/cltbld', 08:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:26 INFO - 'MOZ_NO_REMOTE': '1', 08:43:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:26 INFO - 'NO_EM_RESTART': '1', 08:43:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:26 INFO - 'PWD': '/builds/slave/test', 08:43:26 INFO - 'SHELL': '/bin/bash', 08:43:26 INFO - 'SHLVL': '1', 08:43:26 INFO - 'TERM': 'linux', 08:43:26 INFO - 'TMOUT': '86400', 08:43:26 INFO - 'USER': 'cltbld', 08:43:26 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:26 INFO - '_': '/tools/buildbot/bin/python'} 08:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:43:26 INFO - Cleaning up... 08:43:26 INFO - Return code: 0 08:43:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:26 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:26 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 08:43:26 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 08:43:26 INFO - Using env: {'DISPLAY': ':0', 08:43:26 INFO - 'HOME': '/home/cltbld', 08:43:26 INFO - 'LANG': 'en_US.UTF-8', 08:43:26 INFO - 'LANGUAGE': 'en_US:en', 08:43:26 INFO - 'LOGNAME': 'cltbld', 08:43:26 INFO - 'MAIL': '/var/mail/cltbld', 08:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:26 INFO - 'MOZ_NO_REMOTE': '1', 08:43:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:26 INFO - 'NO_EM_RESTART': '1', 08:43:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:26 INFO - 'PWD': '/builds/slave/test', 08:43:26 INFO - 'SHELL': '/bin/bash', 08:43:26 INFO - 'SHLVL': '1', 08:43:26 INFO - 'TERM': 'linux', 08:43:26 INFO - 'TMOUT': '86400', 08:43:26 INFO - 'USER': 'cltbld', 08:43:26 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:26 INFO - '_': '/tools/buildbot/bin/python'} 08:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:26 INFO - Downloading/unpacking blobuploader==1.2.4 08:43:26 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 08:43:26 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 08:43:26 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 08:43:26 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 08:43:26 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 08:43:26 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 08:43:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:43:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:43:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:43:29 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 08:43:29 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 08:43:29 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 08:43:29 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 08:43:29 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 08:43:29 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 08:43:29 INFO - Downloading docopt-0.6.1.tar.gz 08:43:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:43:29 INFO - Installing collected packages: blobuploader, requests, docopt 08:43:29 INFO - Running setup.py install for blobuploader 08:43:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:43:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:43:29 INFO - Running setup.py install for requests 08:43:29 INFO - Running setup.py install for docopt 08:43:29 INFO - Successfully installed blobuploader requests docopt 08:43:29 INFO - Cleaning up... 08:43:29 INFO - Return code: 0 08:43:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:29 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')]} 08:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:29 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')]} 08:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:29 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:29 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 08:43:29 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 08:43:29 INFO - Using env: {'DISPLAY': ':0', 08:43:29 INFO - 'HOME': '/home/cltbld', 08:43:29 INFO - 'LANG': 'en_US.UTF-8', 08:43:29 INFO - 'LANGUAGE': 'en_US:en', 08:43:29 INFO - 'LOGNAME': 'cltbld', 08:43:29 INFO - 'MAIL': '/var/mail/cltbld', 08:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:29 INFO - 'MOZ_NO_REMOTE': '1', 08:43:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:29 INFO - 'NO_EM_RESTART': '1', 08:43:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:29 INFO - 'PWD': '/builds/slave/test', 08:43:29 INFO - 'SHELL': '/bin/bash', 08:43:29 INFO - 'SHLVL': '1', 08:43:29 INFO - 'TERM': 'linux', 08:43:29 INFO - 'TMOUT': '86400', 08:43:29 INFO - 'USER': 'cltbld', 08:43:29 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:29 INFO - '_': '/tools/buildbot/bin/python'} 08:43:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:30 INFO - Running setup.py (path:/tmp/pip-xnTrIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:30 INFO - Running setup.py (path:/tmp/pip-64r1iU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:30 INFO - Running setup.py (path:/tmp/pip-hYEZzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:30 INFO - Running setup.py (path:/tmp/pip-QJGJyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:30 INFO - Running setup.py (path:/tmp/pip-CdGTC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:30 INFO - Running setup.py (path:/tmp/pip-gaY_XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:30 INFO - Running setup.py (path:/tmp/pip-Gkh0em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:30 INFO - Running setup.py (path:/tmp/pip-PIGOQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:30 INFO - Running setup.py (path:/tmp/pip-ptTyLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:30 INFO - Running setup.py (path:/tmp/pip-9eYNhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:30 INFO - Running setup.py (path:/tmp/pip-9X38wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:30 INFO - Running setup.py (path:/tmp/pip-FEiHYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:31 INFO - Running setup.py (path:/tmp/pip-uOfpvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:31 INFO - Running setup.py (path:/tmp/pip-Uj3t0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:31 INFO - Running setup.py (path:/tmp/pip-wOpSmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:31 INFO - Running setup.py (path:/tmp/pip-YMhtG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:31 INFO - Running setup.py (path:/tmp/pip-lchimT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:43:31 INFO - Running setup.py install for manifestparser 08:43:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:43:31 INFO - Running setup.py install for mozcrash 08:43:31 INFO - Running setup.py install for mozdebug 08:43:31 INFO - Running setup.py install for mozdevice 08:43:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:43:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:43:31 INFO - Running setup.py install for mozfile 08:43:31 INFO - Running setup.py install for mozhttpd 08:43:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Running setup.py install for mozinfo 08:43:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Running setup.py install for mozInstall 08:43:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Running setup.py install for mozleak 08:43:32 INFO - Running setup.py install for mozlog 08:43:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Running setup.py install for moznetwork 08:43:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Running setup.py install for mozprocess 08:43:32 INFO - Running setup.py install for mozprofile 08:43:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Running setup.py install for mozrunner 08:43:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:43:32 INFO - Running setup.py install for mozscreenshot 08:43:32 INFO - Running setup.py install for moztest 08:43:33 INFO - Running setup.py install for mozversion 08:43:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:43:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:43:33 INFO - Cleaning up... 08:43:33 INFO - Return code: 0 08:43:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43: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')]} 08:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43: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')]} 08:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:33 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:33 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 08:43:33 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 08:43:33 INFO - Using env: {'DISPLAY': ':0', 08:43:33 INFO - 'HOME': '/home/cltbld', 08:43:33 INFO - 'LANG': 'en_US.UTF-8', 08:43:33 INFO - 'LANGUAGE': 'en_US:en', 08:43:33 INFO - 'LOGNAME': 'cltbld', 08:43:33 INFO - 'MAIL': '/var/mail/cltbld', 08:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:33 INFO - 'MOZ_NO_REMOTE': '1', 08:43:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:33 INFO - 'NO_EM_RESTART': '1', 08:43:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:33 INFO - 'PWD': '/builds/slave/test', 08:43:33 INFO - 'SHELL': '/bin/bash', 08:43:33 INFO - 'SHLVL': '1', 08:43:33 INFO - 'TERM': 'linux', 08:43:33 INFO - 'TMOUT': '86400', 08:43:33 INFO - 'USER': 'cltbld', 08:43:33 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:33 INFO - '_': '/tools/buildbot/bin/python'} 08:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:33 INFO - Running setup.py (path:/tmp/pip-x0VA86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:33 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)) 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:33 INFO - Running setup.py (path:/tmp/pip-T1ky2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:33 INFO - Running setup.py (path:/tmp/pip-UuQbAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:33 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)) 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:33 INFO - Running setup.py (path:/tmp/pip-TXTBJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:33 INFO - Running setup.py (path:/tmp/pip-dZ15Jc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:33 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)) 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:33 INFO - Running setup.py (path:/tmp/pip-pHjuLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:33 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)) 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:33 INFO - Running setup.py (path:/tmp/pip-LIZDbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:33 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)) 08:43:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:33 INFO - Running setup.py (path:/tmp/pip-_LclYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:34 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:34 INFO - Running setup.py (path:/tmp/pip-fF4AKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:34 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:34 INFO - Running setup.py (path:/tmp/pip-OjjVZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:34 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:34 INFO - Running setup.py (path:/tmp/pip-IC_kmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:34 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:34 INFO - Running setup.py (path:/tmp/pip-UNjjNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:34 INFO - Running setup.py (path:/tmp/pip-EUDll4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:34 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:34 INFO - Running setup.py (path:/tmp/pip-mCfGDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:34 INFO - Running setup.py (path:/tmp/pip-3CbwUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:34 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:34 INFO - Running setup.py (path:/tmp/pip-JHBexe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:34 INFO - Running setup.py (path:/tmp/pip-dEOOGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:34 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)) 08:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:43:34 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)) 08:43:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:43:34 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 08:43:34 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 08:43:34 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 08:43:34 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 08:43:34 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 08:43:34 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 08:43:36 INFO - Downloading blessings-1.6.tar.gz 08:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:43:36 INFO - Installing collected packages: blessings 08:43:36 INFO - Running setup.py install for blessings 08:43:36 INFO - Successfully installed blessings 08:43:36 INFO - Cleaning up... 08:43:37 INFO - Return code: 0 08:43:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:37 INFO - Reading from file tmpfile_stdout 08:43:37 INFO - Current package versions: 08:43:37 INFO - argparse == 1.2.1 08:43:37 INFO - blessings == 1.6 08:43:37 INFO - blobuploader == 1.2.4 08:43:37 INFO - docopt == 0.6.1 08:43:37 INFO - functools32 == 3.2.3-2 08:43:37 INFO - jsonschema == 2.5.1 08:43:37 INFO - manifestparser == 1.1 08:43:37 INFO - mozInstall == 1.12 08:43:37 INFO - mozcrash == 1.0 08:43:37 INFO - mozdebug == 0.1 08:43:37 INFO - mozdevice == 0.49 08:43:37 INFO - mozfile == 1.2 08:43:37 INFO - mozhttpd == 0.7 08:43:37 INFO - mozinfo == 0.9 08:43:37 INFO - mozleak == 0.1 08:43:37 INFO - mozlog == 3.4 08:43:37 INFO - moznetwork == 0.27 08:43:37 INFO - mozprocess == 0.25 08:43:37 INFO - mozprofile == 0.28 08:43:37 INFO - mozrunner == 6.13 08:43:37 INFO - mozscreenshot == 0.1 08:43:37 INFO - mozsystemmonitor == 0.3 08:43:37 INFO - moztest == 0.8 08:43:37 INFO - mozversion == 1.4 08:43:37 INFO - psutil == 3.1.1 08:43:37 INFO - requests == 1.2.3 08:43:37 INFO - wsgiref == 0.1.2 08:43:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:37 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:37 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 08:43:37 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 08:43:37 INFO - Using env: {'DISPLAY': ':0', 08:43:37 INFO - 'HOME': '/home/cltbld', 08:43:37 INFO - 'LANG': 'en_US.UTF-8', 08:43:37 INFO - 'LANGUAGE': 'en_US:en', 08:43:37 INFO - 'LOGNAME': 'cltbld', 08:43:37 INFO - 'MAIL': '/var/mail/cltbld', 08:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:37 INFO - 'MOZ_NO_REMOTE': '1', 08:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:37 INFO - 'NO_EM_RESTART': '1', 08:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:37 INFO - 'PWD': '/builds/slave/test', 08:43:37 INFO - 'SHELL': '/bin/bash', 08:43:37 INFO - 'SHLVL': '1', 08:43:37 INFO - 'TERM': 'linux', 08:43:37 INFO - 'TMOUT': '86400', 08:43:37 INFO - 'USER': 'cltbld', 08:43:37 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:37 INFO - '_': '/tools/buildbot/bin/python'} 08:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 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)) 08:43:37 INFO - Cleaning up... 08:43:37 INFO - Return code: 0 08:43:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:37 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 0x10eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1128a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1389310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, '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': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', '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 08:43:37 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 08:43:37 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 08:43:37 INFO - Using env: {'DISPLAY': ':0', 08:43:37 INFO - 'HOME': '/home/cltbld', 08:43:37 INFO - 'LANG': 'en_US.UTF-8', 08:43:37 INFO - 'LANGUAGE': 'en_US:en', 08:43:37 INFO - 'LOGNAME': 'cltbld', 08:43:37 INFO - 'MAIL': '/var/mail/cltbld', 08:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:37 INFO - 'MOZ_NO_REMOTE': '1', 08:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:37 INFO - 'NO_EM_RESTART': '1', 08:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:37 INFO - 'PWD': '/builds/slave/test', 08:43:37 INFO - 'SHELL': '/bin/bash', 08:43:37 INFO - 'SHLVL': '1', 08:43:37 INFO - 'TERM': 'linux', 08:43:37 INFO - 'TMOUT': '86400', 08:43:37 INFO - 'USER': 'cltbld', 08:43:37 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:37 INFO - '_': '/tools/buildbot/bin/python'} 08:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:43:37 INFO - Cleaning up... 08:43:37 INFO - Return code: 0 08:43:37 INFO - Running post-action listener: _resource_record_post_action 08:43:37 INFO - Running post-action listener: _start_resource_monitoring 08:43:37 INFO - Starting resource monitoring. 08:43:37 INFO - [mozharness: 2017-03-13 15:43:37.724258Z] Finished create-virtualenv step (success) 08:43:37 INFO - [mozharness: 2017-03-13 15:43:37.724782Z] Running install step. 08:43:37 INFO - Running pre-action listener: _resource_record_pre_action 08:43:37 INFO - Running main action method: install 08:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:37 INFO - Reading from file tmpfile_stdout 08:43:37 INFO - Detecting whether we're running mozinstall >=1.0... 08:43:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:43:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:43:38 INFO - Reading from file tmpfile_stdout 08:43:38 INFO - Output received: 08:43:38 INFO - Usage: mozinstall [options] installer 08:43:38 INFO - Options: 08:43:38 INFO - -h, --help show this help message and exit 08:43:38 INFO - -d DEST, --destination=DEST 08:43:38 INFO - Directory to install application into. [default: 08:43:38 INFO - "/builds/slave/test"] 08:43:38 INFO - --app=APP Application being installed. [default: firefox] 08:43:38 INFO - mkdir: /builds/slave/test/build/application 08:43:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:43:54 INFO - Reading from file tmpfile_stdout 08:43:54 INFO - Output received: 08:43:54 INFO - /builds/slave/test/build/application/firefox/firefox 08:43:54 INFO - Running post-action listener: _resource_record_post_action 08:43:54 INFO - [mozharness: 2017-03-13 15:43:54.711935Z] Finished install step (success) 08:43:54 INFO - [mozharness: 2017-03-13 15:43:54.712212Z] Running run-tests step. 08:43:54 INFO - Running pre-action listener: _resource_record_pre_action 08:43:54 INFO - Running main action method: run_tests 08:43:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:43:54 INFO - Python 2.7.3 08:43:54 INFO - Return code: 0 08:43:54 INFO - grabbing minidump binary from tooltool 08:43:54 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')]} 08:43:54 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 0x1386400>, '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 0x13873b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1387840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:43:54 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 08:43:54 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 08:43:54 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 08:43:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:43:54 INFO - Return code: 0 08:43:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:43:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:43:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:43:54 INFO - ENV: RUST_BACKTRACE is now 1 08:43:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:43:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:43:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 08:43:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 08:43:54 INFO - Using env: {'DISPLAY': ':0', 08:43:54 INFO - 'HOME': '/home/cltbld', 08:43:54 INFO - 'LANG': 'en_US.UTF-8', 08:43:54 INFO - 'LANGUAGE': 'en_US:en', 08:43:54 INFO - 'LOGNAME': 'cltbld', 08:43:54 INFO - 'MAIL': '/var/mail/cltbld', 08:43:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:43:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:54 INFO - 'MOZ_NO_REMOTE': '1', 08:43:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:43:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:43:54 INFO - 'NO_EM_RESTART': '1', 08:43:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:54 INFO - 'PWD': '/builds/slave/test', 08:43:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:43:54 INFO - 'RUST_BACKTRACE': '1', 08:43:54 INFO - 'SHELL': '/bin/bash', 08:43:54 INFO - 'SHLVL': '1', 08:43:54 INFO - 'TERM': 'linux', 08:43:54 INFO - 'TMOUT': '86400', 08:43:54 INFO - 'USER': 'cltbld', 08:43:54 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447', 08:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:54 INFO - '_': '/tools/buildbot/bin/python'} 08:43:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 08:43:55 INFO - mozversion application_buildid: 20170313143912 08:43:55 INFO - mozversion application_changeset: 7b19a63862252ffb89bfe1ba79724e76e20fb6f4 08:43:55 INFO - mozversion application_display_name: Nightly 08:43:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:43:55 INFO - mozversion application_name: Firefox 08:43:55 INFO - mozversion application_remotingname: firefox 08:43:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:43:55 INFO - mozversion application_vendor: Mozilla 08:43:55 INFO - mozversion application_version: 55.0a1 08:43:55 INFO - mozversion platform_buildid: 20170313143912 08:43:55 INFO - mozversion platform_changeset: 7b19a63862252ffb89bfe1ba79724e76e20fb6f4 08:43:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:43:55 INFO - mozversion platform_version: 55.0a1 08:43:55 INFO - using testdate: 1489419835 08:43:55 INFO - actual date: 1489419835 08:43:55 INFO - starting webserver on 'localhost:36840' 08:43:55 INFO - SUITE-START | Running 2 tests 08:43:55 INFO - TEST-START | dromaeo_css 08:43:55 INFO - operating with platform_type : linux_ 08:43:55 INFO - Initialising browser for dromaeo_css test... 08:43:55 INFO - TEST-INFO | started process 19073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNQEKT1/profile http://localhost:36840/getInfo.html) 08:43:58 INFO - PID 19073 | __metrics Screen width/height:1600/1200 08:43:58 INFO - PID 19073 | colorDepth:24 08:43:58 INFO - PID 19073 | Browser inner width/height: 1024/697 08:43:58 INFO - PID 19073 | __metrics 08:43:59 INFO - PID 19073 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:43:59 INFO - PID 19073 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:44:00 INFO - TEST-INFO | 19073: exit 0 08:44:00 INFO - Browser initialized. 08:44:00 INFO - Running cycle 1/1 for dromaeo_css test... 08:44:00 INFO - TEST-INFO | started process 19167 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNQEKT1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:12 INFO - PID 19167 | RSS: Main: 144175104 08:44:12 INFO - PID 19167 | 08:47:13 INFO - PID 19167 | Cycle 1(1): loaded http://localhost:36840/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36840/tests/dromaeo/cssquery-ext.html) 08:47:16 INFO - PID 19167 | RSS: Main: 360177664 08:47:16 INFO - PID 19167 | 08:49:03 INFO - PID 19167 | ************************* 08:49:03 INFO - PID 19167 | A coding exception was thrown and uncaught in a Task. 08:49:03 INFO - PID 19167 | 08:49:03 ERROR - PID 19167 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:49:03 INFO - PID 19167 | Full stack: 08:49:03 INFO - PID 19167 | ************************* 08:50:18 INFO - PID 19167 | Cycle 1(1): loaded http://localhost:36840/tests/dromaeo/cssquery-ext.html (next: http://localhost:36840/tests/dromaeo/cssquery-jquery.html) 08:50:19 INFO - PID 19167 | RSS: Main: 240017408 08:50:19 INFO - PID 19167 | 08:53:21 INFO - PID 19167 | Cycle 1(1): loaded http://localhost:36840/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36840/tests/dromaeo/cssquery-mootools.html) 08:53:21 INFO - PID 19167 | RSS: Main: 180494336 08:53:21 INFO - PID 19167 | 08:56:23 INFO - PID 19167 | Cycle 1(1): loaded http://localhost:36840/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36840/tests/dromaeo/cssquery-prototype.html) 08:56:24 INFO - PID 19167 | RSS: Main: 176640000 08:56:24 INFO - PID 19167 | 08:59:27 INFO - PID 19167 | Cycle 1(1): loaded http://localhost:36840/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36840/tests/dromaeo/cssquery-yui.html) 08:59:27 INFO - PID 19167 | RSS: Main: 186912768 08:59:27 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | Cycle 1(1): loaded http://localhost:36840/tests/dromaeo/cssquery-yui.html (next: http://localhost:36840/tests/dromaeo/cssquery-dojo.html) 09:02:30 INFO - PID 19167 | RSS: Main: 278474752 09:02:30 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | __start_tp_report 09:02:30 INFO - PID 19167 | _x_x_mozilla_page_load 09:02:30 INFO - PID 19167 | _x_x_mozilla_page_load_details 09:02:30 INFO - PID 19167 | |i|pagename|runs| 09:02:30 INFO - PID 19167 | |0;dojo.html;3210.39002589508;3232.2242661761184;3244.059222825381;3280.7375409967203;3285.540024396585;5503.669779813204;5603.2435621191125;5616;5618.466245706656;5629.380768115504;5514.228008078872;5547.805826796063;5549.056660367737;5644.238027866237;5687.7156142192825;4989.077020751154;5045.016221836743;5064.493550644925;5066.417362003362;5082.618803589727;4973.5772459341;5007.4491805901325;5031.547160755531;5055.418626857903;5061.519155680223;4973.552380285755;5015.3730783652045;5056.443791182967;5063.974680126613;5118;4993.400791904958;5021.046001259749;5022.296878437021;5026.648134630577;5029.698218106925;4991.126552853236;4994.775235114393;4998.350214472095;5002.349694539723;5006.4492905780335;5499.862503437406;5503.532199763025;5522.723863806794;5553.666779993213;5566.443355664442;5954.404559544054;5963.821085367446;5965.761369545213;5965.850853728648;5967.761289548413;5872.794452194153;5959.523238140939;6006.279246490405;6022.909656355158;6025.6987150642735;5495.313085864267;5495.972520137414;5496.80761173361;5499.312585926759;5505.944940550583;5951.345352011275;5997.7001149942325;6001.789937352171;6005.519558435315;6015.248093988252;5767.250257466587;5786.450287222707;5858.2677165354335;5914.9112763308585;5941.22764040672;6026.246719160105;6028.758849645965;6082.75668973245;6087.117367981707;6097.908531372033;6004.459598636144;6004.909926351108;6009.1587177795145;6009.939900601025;6013;5914.881702366015;5983.0128028875615;5988.640681559121;6014.699265036731;6029.8191054268445;4583.702059366131;4585.564371384713;4589.082183563234;4592.816287348535;4593.954060459419;4066.4510291110323;4118.464599602033;4126.339785634284;4127.9174416511505;4133.7106402551535;5632.802851900164;5650.7174641267775;5653.604247702621;5665.49010589049;5702.201691763156;6042.365551617105;6048.274207095177;6050.606710563799;6050.939490605126;6051.243594550681;5493.258410114663;5541.750621222055;5542.75057622408;5648.71756412186;5656.066748986454;5111.233315002779;5123.410807757155;5143.897122057613;5156.381234251914;5228.346456692913;6129.96935015331;6154.3845615438995;6164.907526387018;6172.660503672251;6180.783672571439;5937.465628093492;5948.613340132878;5953.404659534012;5960.1357803117435;6021.096835474538;6104.511639068775;6139.614800607318;6147.477464415575;6166.722497487534;6190.41191086846;5881.117832325185;5886.323072846678;5887.4406931341455;5888.793892213752;5891.882162356815;5885.793997210077;5948.910766338422;5985.760569577343;5995.700214989321;5996.580239383201;5872.031114865999;5913.408659134052;5920.437558431942;5922.318933322723;5931.347551769388;5839.678817665069;5853.795117171049;5856.94143058581;5857.121431785266;5904.793332233351;5833.67914764692;5836.124581312667;5846.035404158266;5847.502962248086;5852.005159123045;5823.242978412779;5831.271091113611;5881.7647294107755;5892.381299963442;5892.499137573182;5687.5734319927;5714.942850571608;5721.942780572307;5723.484886360247;5724.74238659253 09:02:30 INFO - PID 19167 | |1;ext.html;763.6563546404118;784.3920961255028;841.7096101844861;850.8851305073813;854.7478493844319;36104.819475902616;37026.07434814128;37204.25591488172;37243.255134897285;37283.440748388726;38603.806980965055;38997;39006.21987560247;39056.60943390565;39060.80469597655;7146.32109949554;7278.9272107278775;8672.566371681438;8775.95612021939;8844;9816.50917454128;10024.548895299693;10028.298019138663;10045.89954100457;10054.849177262311;10260.846087308697;10334.483275836215;10335;10338.32800867945;10338.638147664833;10221.693349199535;10250.641227557075;10275.126614237799;10287.948560257151;10303.742406439898;10232.795344093076;10363.533640986174;10378.532966016548;10381.197076789225;10386.324888882275;40227.39658905119;40520.79739601283;40557.59442405568;40607.18785624301;40611.390829137585;3714.145746478298;3906.160175562268;3922.17634296798;3929.057026313693;3951.4072889066583;25473.23580292594;25579.744202557922;25740.613890791654;25766.355841103934;26472.60291095635;3954.703397245218;3976.3240249157425;4031.5766844481495;4042.2521833460905;4079.571644977294;183814;184832.07583961994;186655.06672466686;186773;187586;37672.24655506875;38309.42535861964;45815.54184458113;46470.30294545584;46651.46879436882;48899.42058980792;48935.5106448931;49147.11940328357;49163.50836491661;49433.25850112251;14616.707665846623;14708;14727.705445891022;14747.188904610135;14781.778273325908;11372.658820235407;11468;11525.88474115265;11634.883651163549;11666.88333116658;14410.495632652806;14463.49377771773;14469.710605788036;14544.563663090124;14662.63343416423;5724.4561766632105;5732.140178973187;5758.165066065397;5766.452187042224;5843.620164689281;950.4344914775697;959.1128206409184;977.1791694976254;982.1847866271121;991.7074463033423;24677.506449870903;24693.382665433506;24738.381540461633;24898.253052408458;24996.625050624254;1022.4683164754287;1023.4319952426475;1026.4200726589454;1034.9586016559253;1059.8304271316551;12665.240085594894;13018;13220.272884991226;13233;13469.663258418423;327049;327417;328315;328517;329609;52126;52409.47590524046;52544.21183682248;52589;52663.73668131712;1378.81386012889;1420.4389266239777;1426.0588011912296;1440.4310297432664;1450.840407555168;24102.51794964126;26383.60424593594;29361.55957660594;29681;30061.549076764302;51927.740361297954;52401.47598523966;52495.475045249055;52575;52576.474235257156;343729;346143;348429;349077;349288;26292.474150517264;29528.409431811673;31356.529652055695;31479.52780708337;34936.30127397387;37372.25255494929;38170.809145954096;39293.41059884161;40322.59677403188;40915.181696366504;33919;33919.491207631414;34066.65933340635;34186.65813341934;34552;33435.33129337351;33788.83105584457;33805.492917606745;33945.49081763726;34218;34589.65410345864;35613.46579801357;35719;35833.46249806307;35845.82077089598;28539.714602854536;29929.401411972074;30379.84810076024;30709;30751.2312192197;24528.87735561311;24700.25899223026;24726.629100563867;29604.259893502836;30189.245268868453 09:02:30 INFO - PID 19167 | |2;jquery.html;390.6894019254692;393.8503368719887;398.92420440116393;403.4029636138513;426.12431453363325;20080.29718959837;20503.897480512576;20792.584148317044;20808;21275.680864787042;20254.08856601453;20453;20462.18151273951;21051.789482105214;21215.151393944223;6542.41118299353;6750.1562304711915;6829.112215411989;6833.043373927654;6833.453323734114;6492.350764923493;6654.966725166368;6682.6658667066695;6728.663566821664;6789.049533065375;6730.697118629649;6744.2244141923575;6751.493637977147;6751.797446076625;6753.189617245913;6511.251206111287;6713.731450741964;6727.428168605675;6735.966320168368;6741.764038258662;6296.716647750863;6342.849438471542;6361.300256971729;6563.606183628997;6564.376384243489;24530.632040519406;24913.01739652077;25002.61065445371;25068.122615708544;25306.240812775646;2783.4572258409617;2894.2908987298038;2894.52240380337;2897.1453421240785;2914.4608247474284;14430.134191948411;14858.479953201586;15123.848761512354;15278.083315001024;15498.922505387514;2177.3032629558547;2300.344401845466;2314.409825494505;2408.8675122868235;2442.255112190781;103858.48070759598;105823;106164.46917765362;106365;107873;18486;18518.722219166724;18678.626427471376;18832;19061.332853350064;33272.33821294291;33543.142538607884;33601.32797344039;33671;33867.830660846535;9730.610775568897;9838.704838854846;10032.64885728996;10052.195824334036;10079.64721234768;7954.761357159295;8010.3191228745045;8164.387670924704;8175.754727358189;8224.588770561448;9553.379030363005;9627.470489123025;9780.266480014028;9919.454430006275;10223.428231613789;3865.4723745044294;3955.841766329372;4058.776767277769;4082.9183416331507;4118.398977390746;859.7506723050245;881.1803625513295;883.4831623500324;889.9688510902217;914.4558987402485;20423.387298381072;21249.68125478119;21423.78576214218;21521.601095928716;21704.457388565414;890.7149712092198;899.1772528136812;911.3848152497038;917.4816228830689;920.4385324951774;11030.28303160292;12009.819852702216;13339.399727012307;13710.52013179534;14199;617533;618191;618267;622881;622980;107070;109922.45038774915;110502.44748776205;112855;113117.43441282905;1312.1274352555633;1316.0655934286572;1362.2779926638898;1366.3373263966969;1366.7357694132888;19430.61138777188;19545.218191272757;20108.59782804365;20245.898770506054;20309.89845050765;49986;51016.234756479425;51188.74405627948;51463;51924.74037629788;65277;65419;66770.33229667836;67267.32732672806;69135.65432172807;11615.477303521531;11991.481788824096;12021.939890300493;12236.877631223573;12477.812832807696;12786.424610892718;13778.793318100035;13790.241536715577;14104.717905642035;14649.26753662334;11631.069514438994;11806.232594881305;12580.182288151242;12856.678583035498;12927.5475358362;11502;11584.710382240175;11713.824292635787;11928.554646297314;12392.690182745504;11681.474333655176;11736.237144585575;12775.744485110432;13015.023873209358;13107.868921310665;12200.450979705756;12229.14395992272;12301.569445069379;12504.249745015328;12716.237025778113;12254.693632659255;12264.32546209967;12363.62909112728;12700.110992230824;12753.872461275269 09:02:30 INFO - PID 19167 | |3;mootools.html;1388.9513867014657;1501.1968596800714;1516.5981015031025;1525.9160746158964;1585.865201457876;8972.551372431377;10214.131798797102;10678.145748340134;10691.83962240567;10702.250842441013;10386.27296089274;10545.47272636367;10571;10577.206709496799;10587.311824731407;1821.5810363616376;2083.57286756215;2134.125008746414;2136.327056977047;2136.359092272312;1909.9045047747557;2114.534802343482;2147.5168087180336;2174.3694328644674;2178.7930146636045;2080.4070839810656;2106.1259577275487;2109.7679255281905;2123.543438160795;2128.957420851582;2050.866693664907;2054.0551346380685;2054.989725051365;2062.3297428335777;2066.5763518478675;1986.3147214211074;2025.6961455781604;2029.0159272752703;2093.1418118571464;2096.9790302096935;11001.22991390603;11071;11096.05683516902;11096.61161859331;11103.000729934345;4949.208126699711;5127.48725127488;5131.846044618667;5134.255532947739;5139.203423469368;3000.354923691417;3106.8135911845134;3183.363327334542;3183.761217908643;3244.9675503244903;1132.6947519125818;1234.382808595702;1243.7636848998752;1260.0864373329264;1297.7015286484161;15611.531654050397;15974.920125399414;15995;16022.118783466905;16060.919695401448;4163.625273725379;4278.850240241639;4306.289462238696;4323.63249123828;4330.870073897788;11515;11618.07055435563;11635.301881887113;11635.709107272216;11650;5161.870953226125;5206.687598744087;5212.765425555859;5213.400458947193;5221.6083793715625;4207.852725154605;4329.826806927687;4523.276275795923;4621.584057434847;4829.855104346875;3438.5014172944784;3459.4291941829315;3522.18989632386;3524.22467057247;3551.7691350062164;914.0448231597996;922.1930810540777;925.9027802080819;931.7437704631145;932.883389576303;692.6571347183076;695.1276148433683;714.1822613107962;725.0791494801573;738.6971341749856;6218.689065546795;6284.24589049317;6333.841653958688;6402.743890244431;6617;1025.7435641089728;1027.7379268286538;1043.186314674555;1061.4109169410897;1064.69123954054;4007.1184339445276;4063.7358571692744;4082.5509193988637;4085.5914408558906;4097.119119389286;12909;13276.070675052846;13324.600261992156;13333.266670333225;13344.5329413472;12965.611031669065;13079.476820927246;13101.672458188621;13115.08194426381;13115.737685246242;8682.739517814476;9005.729828105168;9034.2320902724;9562.18721408688;9722.708318750449;6184.5980011299125;6274.811755647155;6400.55196136266;6414.102025716496;6518.2504012037325;11494;11625.12811539121;11719.355435451133;11845.881541184477;11902.166848320534;11803.468843902217;11862.644120676394;11871.109666775199;11998.76002479963;12085.214461060003;7776.92223077762;8061.959690201512;9141.63433462654;9361.297902657192;9390.530473476163;10128.746781330527;10324.277300588887;10371.636992705218;10373.066424021874;10389.168866490814;8387.95806020966;8442.071372148936;8479.872801908099;8515.105913878953;8527.232549070613;8584.313254939465;8658.090900455361;8777.210051095177;8991;8997.685081022133;7852.725154619561;8000.479968802009;8002.51984880909;8002.839943200987;8014.118446970712;5691.544676425793;5768.163616275533;5814.622049566765;5846.56150788684;6021.87956240864;5648.943510564842;5891.705414729161;5925.792597259076;5937.732802023833;6009.158717779602 09:02:30 INFO - PID 19167 | |4;prototype.html;388.67933954487535;398.4043854437616;404.5347849972533;406.4431728531911;409.0305974839629;5627.352854421738;5743.483086522207;5753.827385178441;5772.538196944245;5780.942190578104;5503.944960550403;5523.530499907512;5540.196671482643;5683.602147849652;5697.230873832032;4463.620592249663;4496.932546011821;4500.257457519506;4519.932201016971;4531.705439146462;4514.255147900592;4528.547145285462;4546.454425468932;4546.522615125414;4555.385023021883;4384.671149663772;4415.410452237192;4476.776161191944;4505.098980203956;4531.864044078667;4472.485664148599;4472.5303843096335;4481.350204220386;4484.080763443486;4488.798004089824;4048.109415928491;4064.2074795414906;4066.694997875142;4068.3083875741195;4090.6931980101313;5275.594878276605;5542.63972841764;5552.41699621542;5566.86082847928;5587.720613969326;5472.863178420532;5475;5507.697076660782;5513.972430137864;5526.088195447746;5970.164177015221;6013.75944962201;6042.697865106771;6044.788432404889;6062.636241825505;4672.2057250267535;4814.724098114003;4820.469748327681;4836.733979631119;4905.460399356068;5988.371221021774;6165.811137046328;6210.65841378724;6274.654893980829;6330.417395651088;4606.355110284563;4881.096997055556;4892.633052521075;4904.4605093439695;4912.189488734391;4956.405231372259;5035.672681275779;5058.468860769566;5075.695458272516;5103.642745007888;5439.456054394529;5465.426130256265;5479.726013699299;5486.91769623456;5500.119980803052;5888.852778680482;5965.194698715704;6010.879782404328;6015.984645143838;6029.939700603026;1758.8241175882565;1768.4694591622463;1774.8935063896208;1780.9910950445164;1782.5900042990186;1294.411042975444;1334.4662135145825;1357.7827547592337;1358.6209996853202;1371.5748118083427;5713.714314284269;5721.370649228582;5728.627639203439;5746.649537358718;5761.4526619971875;5954.672493012926;6130.478909292671;6172.506199504029;6184.752609895644;6203.31763506014;5717.142428635738;5728.398518155617;5738.655680659173;5757.712114394263;5760.07838745807;3154.7003034711665;3253.6746325367276;3279.9016029518675;3283.195617073832;3284.4088064148204;6369.840753981095;6379.74481020754;6382.393672601095;6396.456301214356;6401.135846660642;6129;6320.367963203643;6322.936770632235;6325.240971083499;6360.968195159087;6011.316831287247;6322.968385158045;6337.207849018872;6355.618662880242;6356.459700925473;5952.851178720567;6012.248468941382;6033.0136582392;6035.728392222449;6050.485708714633;6009.789657361971;6046.425589568984;6056.242969628796;6074.544409169418;6077.574569780073;6195.318514963441;6280.309165991646;6308.306086330408;6325.703230837576;6426.775062872777;5012.749362531932;5023.321851549994;5029.371328583927;5032.521910418504;5048.949510504848;5962.2249107615735;6177.4749146321265;6181.165542651865;6201.224846894138;6201.503879689524;5967.492763115184;5986.820395388146;5988.700564971821;6014.097885317237;6037.577369584263;5904.3800400959;5934.881302374015;5934.970325148347;5938.8218353449465;5966.134910437875;5800.651960882361;5927.2590926134235;5951.702414879325;6010.398960104129;6018.157457955802;6011.819645410645;6026.909596356146;6029.909551356645;6037.607555508878;6053.394660534088;5729.4843464088435;5786.884262314814;5799.884002319845;5899.495628614676;5911.379305172895 09:02:30 INFO - PID 19167 | |5;yui.html;758.0865057605586;758.6244808819636;796.1560745609654;835.5696816139687;863.7797361672773;15244.237788110591;15411.92294038531;15771.526854194379;15881.205939703012;16021.599460013505;14924.253787310645;15062.924685376558;15081.62295942602;15087.471938482155;16604.83395166051;836.1429534726923;851.3699862102031;856.4946681457939;856.9571521423935;857.014433401587;811.1442428238206;840.0381563110266;845.8181490979438;846.5047946951041;847.076686411811;884.7257350221452;890.5013192612125;891.1712107739831;891.6700820696351;893.1425831202054;877.0615441477589;879.0725784297564;880.7533890510667;883.5582208895552;884.5975081337975;646.7994921574327;649.9415052645285;650;650.39513252675;650.6161364794793;14493.492727754478;15127.924360378238;15330.923345383313;15514.456994005155;15564.455244066403;14860.182689952047;15357.385704571803;15766.684666306724;16254.918725406416;16434.589135271595;11713.70715732105;11796.941015294955;11919.404029798563;12050.337231452266;12146.45340959659;3735.62643735627;3778.3199024175638;3802.524684414448;3854.8650797222244;3936.960630393688;85304.65949820823;88588.5570572143;89037;89552.55223723906;91022;12582.119251652297;12615.116941814169;12687.740176025784;12788.744225115446;12924.999999999813;25601.48797024049;25623.487530249662;26002;26284.21147365582;26323.73676263251;8314.6674133034;8523.74428767138;8621.224089831945;8684.739457816275;8768;4094.877153685394;4166.250074986531;4242.406063151161;4257.63810076147;4273.529911709709;3539.5398598182073;3601.2077342984503;3635.763675361093;3636.709063274932;3642.3625865473437;561.9662820230799;562.4853259267805;565.5475619504463;581.1573218832625;583.6323116436618;754.6905768634833;768.9577073261024;770.691723310669;810.1048341582584;811.2941740685641;10226.795464090677;10485.423301718478;10742.516586753614;11639.47622356996;11713.765724685458;749.0786332810674;761.3300295739714;771.4638326363236;772.4978763803573;779.5712358202966;4956.231784073402;5100.744962751847;5158.045761534053;5165.845024649267;5171.741412929338;74667;75324.62337688386;77432.61283693546;78293.60853195812;79071.60464197642;68244;70156.298437016;71123.6443817788;71656.64171679213;72187;60954.39045609487;61090;61196;61338.38661613416;62110.23023783553;10085.899141008496;10115.898841011496;10197.74505637365;10385.324953877975;10437.68686939393;42908;43204.56795432005;44074.559254407046;44346;44388.33417498805;50167.74916125542;50197.24704129368;50400.49599504105;50466.486005419894;50533.242001370745;12282.263064216175;12363.75272494563;12368.134230603771;12581.811272830731;12622.684432888884;13536.187828730308;13604.863951360756;13641.113327633671;13669.794953075512;13735.175889446666;12438.937805310916;12482.875171248534;12569.308688022247;12643.114981951543;12659.873401266237;12547.498100075894;12676.492940282655;12824;13172.209667419986;13375.799363009757;12929.482820687068;13348.332583370599;13405.396757146147;13421.157684630738;13843.307834608027;10249.538770755185;10548;10844.837327440253;10846.186536009673;11126.109911207246;10434.843477347998;10506.789864202827;10527.73680657959;10554.525046372779;10662.520186591466 09:02:30 INFO - PID 19167 | __end_tp_report 09:02:30 INFO - PID 19167 | __start_cc_report 09:02:30 INFO - PID 19167 | _x_x_mozilla_cycle_collect,2393 09:02:30 INFO - PID 19167 | __end_cc_report 09:02:30 INFO - PID 19167 | __startTimestamp1489420950761__endTimestamp 09:02:30 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | ------- Summary: start ------- 09:02:30 INFO - PID 19167 | Number of tests: 6 09:02:30 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | [#0] dojo.html Cycles:165 Average:5542.73 Median:5823.24 stddev:630.40 (10.8%) stddev-sans-first:605.20 09:02:30 INFO - PID 19167 | Values: 3210.4 3232.2 3244.1 3280.7 3285.5 5503.7 5603.2 5616.0 5618.5 5629.4 5514.2 5547.8 5549.1 5644.2 5687.7 4989.1 5045.0 5064.5 5066.4 5082.6 4973.6 5007.4 5031.5 5055.4 5061.5 4973.6 5015.4 5056.4 5064.0 5118.0 4993.4 5021.0 5022.3 5026.6 5029.7 4991.1 4994.8 4998.4 5002.3 5006.4 5499.9 5503.5 5522.7 5553.7 5566.4 5954.4 5963.8 5965.8 5965.9 5967.8 5872.8 5959.5 6006.3 6022.9 6025.7 5495.3 5496.0 5496.8 5499.3 5505.9 5951.3 5997.7 6001.8 6005.5 6015.2 5767.3 5786.5 5858.3 5914.9 5941.2 6026.2 6028.8 6082.8 6087.1 6097.9 6004.5 6004.9 6009.2 6009.9 6013.0 5914.9 5983.0 5988.6 6014.7 6029.8 4583.7 4585.6 4589.1 4592.8 4594.0 4066.5 4118.5 4126.3 4127.9 4133.7 5632.8 5650.7 5653.6 5665.5 5702.2 6042.4 6048.3 6050.6 6050.9 6051.2 5493.3 5541.8 5542.8 5648.7 5656.1 5111.2 5123.4 5143.9 5156.4 5228.3 6130.0 6154.4 6164.9 6172.7 6180.8 5937.5 5948.6 5953.4 5960.1 6021.1 6104.5 6139.6 6147.5 6166.7 6190.4 5881.1 5886.3 5887.4 5888.8 5891.9 5885.8 5948.9 5985.8 5995.7 5996.6 5872.0 5913.4 5920.4 5922.3 5931.3 5839.7 5853.8 5856.9 5857.1 5904.8 5833.7 5836.1 5846.0 5847.5 5852.0 5823.2 5831.3 5881.8 5892.4 5892.5 5687.6 5714.9 5721.9 5723.5 5724.7 09:02:30 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | [#1] ext.html Cycles:180 Average:44568.32 Median:33862.25 stddev:77811.47 (229.8%) stddev-sans-first:77960.25 09:02:30 INFO - PID 19167 | Values: 763.7 784.4 841.7 850.9 854.7 36104.8 37026.1 37204.3 37243.3 37283.4 38603.8 38997.0 39006.2 39056.6 39060.8 7146.3 7278.9 8672.6 8776.0 8844.0 9816.5 10024.5 10028.3 10045.9 10054.8 10260.8 10334.5 10335.0 10338.3 10338.6 10221.7 10250.6 10275.1 10287.9 10303.7 10232.8 10363.5 10378.5 10381.2 10386.3 40227.4 40520.8 40557.6 40607.2 40611.4 3714.1 3906.2 3922.2 3929.1 3951.4 25473.2 25579.7 25740.6 25766.4 26472.6 3954.7 3976.3 4031.6 4042.3 4079.6 183814.0 184832.1 186655.1 186773.0 187586.0 37672.2 38309.4 45815.5 46470.3 46651.5 48899.4 48935.5 49147.1 49163.5 49433.3 14616.7 14708.0 14727.7 14747.2 14781.8 11372.7 11468.0 11525.9 11634.9 11666.9 14410.5 14463.5 14469.7 14544.6 14662.6 5724.5 5732.1 5758.2 5766.5 5843.6 950.4 959.1 977.2 982.2 991.7 24677.5 24693.4 24738.4 24898.3 24996.6 1022.5 1023.4 1026.4 1035.0 1059.8 12665.2 13018.0 13220.3 13233.0 13469.7 327049.0 327417.0 328315.0 328517.0 329609.0 52126.0 52409.5 52544.2 52589.0 52663.7 1378.8 1420.4 1426.1 1440.4 1450.8 24102.5 26383.6 29361.6 29681.0 30061.5 51927.7 52401.5 52495.5 52575.0 52576.5 343729.0 346143.0 348429.0 349077.0 349288.0 26292.5 29528.4 31356.5 31479.5 34936.3 37372.3 38170.8 39293.4 40322.6 40915.2 33919.0 33919.5 34066.7 34186.7 34552.0 33435.3 33788.8 33805.5 33945.5 34218.0 34589.7 35613.5 35719.0 35833.5 35845.8 28539.7 29929.4 30379.8 30709.0 30751.2 24528.9 24700.3 24726.6 29604.3 30189.2 09:02:30 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | [#2] jquery.html Cycles:180 Average:36211.84 Median:2307.38 stddev:102148.40 (4427.0%) stddev-sans-first:102399.54 09:02:30 INFO - PID 19167 | Values: 390.7 393.9 398.9 403.4 426.1 20080.3 20503.9 20792.6 20808.0 21275.7 20254.1 20453.0 20462.2 21051.8 21215.2 6542.4 6750.2 6829.1 6833.0 6833.5 6492.4 6655.0 6682.7 6728.7 6789.0 6730.7 6744.2 6751.5 6751.8 6753.2 6511.3 6713.7 6727.4 6736.0 6741.8 6296.7 6342.8 6361.3 6563.6 6564.4 24530.6 24913.0 25002.6 25068.1 25306.2 2783.5 2894.3 2894.5 2897.1 2914.5 14430.1 14858.5 15123.8 15278.1 15498.9 2177.3 2300.3 2314.4 2408.9 2442.3 103858.5 105823.0 106164.5 106365.0 107873.0 18486.0 18518.7 18678.6 18832.0 19061.3 33272.3 33543.1 33601.3 33671.0 33867.8 9730.6 9838.7 10032.6 10052.2 10079.6 7954.8 8010.3 8164.4 8175.8 8224.6 9553.4 9627.5 9780.3 9919.5 10223.4 3865.5 3955.8 4058.8 4082.9 4118.4 859.8 881.2 883.5 890.0 914.5 20423.4 21249.7 21423.8 21521.6 21704.5 890.7 899.2 911.4 917.5 920.4 11030.3 12009.8 13339.4 13710.5 14199.0 617533.0 618191.0 618267.0 622881.0 622980.0 107070.0 109922.5 110502.4 112855.0 113117.4 1312.1 1316.1 1362.3 1366.3 1366.7 19430.6 19545.2 20108.6 20245.9 20309.9 49986.0 51016.2 51188.7 51463.0 51924.7 65277.0 65419.0 66770.3 67267.3 69135.7 11615.5 11991.5 12021.9 12236.9 12477.8 12786.4 13778.8 13790.2 14104.7 14649.3 11631.1 11806.2 12580.2 12856.7 12927.5 11502.0 11584.7 11713.8 11928.6 12392.7 11681.5 11736.2 12775.7 13015.0 13107.9 12200.5 12229.1 12301.6 12504.2 12716.2 12254.7 12264.3 12363.6 12700.1 12753.9 09:02:30 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | [#3] mootools.html Cycles:180 Average:6469.58 Median:3145.09 stddev:4214.03 (134.0%) stddev-sans-first:4208.56 09:02:30 INFO - PID 19167 | Values: 1389.0 1501.2 1516.6 1525.9 1585.9 8972.6 10214.1 10678.1 10691.8 10702.3 10386.3 10545.5 10571.0 10577.2 10587.3 1821.6 2083.6 2134.1 2136.3 2136.4 1909.9 2114.5 2147.5 2174.4 2178.8 2080.4 2106.1 2109.8 2123.5 2129.0 2050.9 2054.1 2055.0 2062.3 2066.6 1986.3 2025.7 2029.0 2093.1 2097.0 11001.2 11071.0 11096.1 11096.6 11103.0 4949.2 5127.5 5131.8 5134.3 5139.2 3000.4 3106.8 3183.4 3183.8 3245.0 1132.7 1234.4 1243.8 1260.1 1297.7 15611.5 15974.9 15995.0 16022.1 16060.9 4163.6 4278.9 4306.3 4323.6 4330.9 11515.0 11618.1 11635.3 11635.7 11650.0 5161.9 5206.7 5212.8 5213.4 5221.6 4207.9 4329.8 4523.3 4621.6 4829.9 3438.5 3459.4 3522.2 3524.2 3551.8 914.0 922.2 925.9 931.7 932.9 692.7 695.1 714.2 725.1 738.7 6218.7 6284.2 6333.8 6402.7 6617.0 1025.7 1027.7 1043.2 1061.4 1064.7 4007.1 4063.7 4082.6 4085.6 4097.1 12909.0 13276.1 13324.6 13333.3 13344.5 12965.6 13079.5 13101.7 13115.1 13115.7 8682.7 9005.7 9034.2 9562.2 9722.7 6184.6 6274.8 6400.6 6414.1 6518.3 11494.0 11625.1 11719.4 11845.9 11902.2 11803.5 11862.6 11871.1 11998.8 12085.2 7776.9 8062.0 9141.6 9361.3 9390.5 10128.7 10324.3 10371.6 10373.1 10389.2 8388.0 8442.1 8479.9 8515.1 8527.2 8584.3 8658.1 8777.2 8991.0 8997.7 7852.7 8000.5 8002.5 8002.8 8014.1 5691.5 5768.2 5814.6 5846.6 6021.9 5648.9 5891.7 5925.8 5937.7 6009.2 09:02:30 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | [#4] prototype.html Cycles:180 Average:5149.03 Median:5729.06 stddev:1414.10 (24.7%) stddev-sans-first:1372.19 09:02:30 INFO - PID 19167 | Values: 388.7 398.4 404.5 406.4 409.0 5627.4 5743.5 5753.8 5772.5 5780.9 5503.9 5523.5 5540.2 5683.6 5697.2 4463.6 4496.9 4500.3 4519.9 4531.7 4514.3 4528.5 4546.5 4546.5 4555.4 4384.7 4415.4 4476.8 4505.1 4531.9 4472.5 4472.5 4481.4 4484.1 4488.8 4048.1 4064.2 4066.7 4068.3 4090.7 5275.6 5542.6 5552.4 5566.9 5587.7 5472.9 5475.0 5507.7 5514.0 5526.1 5970.2 6013.8 6042.7 6044.8 6062.6 4672.2 4814.7 4820.5 4836.7 4905.5 5988.4 6165.8 6210.7 6274.7 6330.4 4606.4 4881.1 4892.6 4904.5 4912.2 4956.4 5035.7 5058.5 5075.7 5103.6 5439.5 5465.4 5479.7 5486.9 5500.1 5888.9 5965.2 6010.9 6016.0 6029.9 1758.8 1768.5 1774.9 1781.0 1782.6 1294.4 1334.5 1357.8 1358.6 1371.6 5713.7 5721.4 5728.6 5746.6 5761.5 5954.7 6130.5 6172.5 6184.8 6203.3 5717.1 5728.4 5738.7 5757.7 5760.1 3154.7 3253.7 3279.9 3283.2 3284.4 6369.8 6379.7 6382.4 6396.5 6401.1 6129.0 6320.4 6322.9 6325.2 6361.0 6011.3 6323.0 6337.2 6355.6 6356.5 5952.9 6012.2 6033.0 6035.7 6050.5 6009.8 6046.4 6056.2 6074.5 6077.6 6195.3 6280.3 6308.3 6325.7 6426.8 5012.7 5023.3 5029.4 5032.5 5048.9 5962.2 6177.5 6181.2 6201.2 6201.5 5967.5 5986.8 5988.7 6014.1 6037.6 5904.4 5934.9 5935.0 5938.8 5966.1 5800.7 5927.3 5951.7 6010.4 6018.2 6011.8 6026.9 6029.9 6037.6 6053.4 5729.5 5786.9 5799.9 5899.5 5911.4 09:02:30 INFO - PID 19167 | 09:02:30 INFO - PID 19167 | [#5] yui.html Cycles:180 Average:17893.35 Median:4130.56 stddev:22958.12 (555.8%) stddev-sans-first:22986.47 09:02:30 INFO - PID 19167 | Values: 758.1 758.6 796.2 835.6 863.8 15244.2 15411.9 15771.5 15881.2 16021.6 14924.3 15062.9 15081.6 15087.5 16604.8 836.1 851.4 856.5 857.0 857.0 811.1 840.0 845.8 846.5 847.1 884.7 890.5 891.2 891.7 893.1 877.1 879.1 880.8 883.6 884.6 646.8 649.9 650.0 650.4 650.6 14493.5 15127.9 15330.9 15514.5 15564.5 14860.2 15357.4 15766.7 16254.9 16434.6 11713.7 11796.9 11919.4 12050.3 12146.5 3735.6 3778.3 3802.5 3854.9 3937.0 85304.7 88588.6 89037.0 89552.6 91022.0 12582.1 12615.1 12687.7 12788.7 12925.0 25601.5 25623.5 26002.0 26284.2 26323.7 8314.7 8523.7 8621.2 8684.7 8768.0 4094.9 4166.3 4242.4 4257.6 4273.5 3539.5 3601.2 3635.8 3636.7 3642.4 562.0 562.5 565.5 581.2 583.6 754.7 769.0 770.7 810.1 811.3 10226.8 10485.4 10742.5 11639.5 11713.8 749.1 761.3 771.5 772.5 779.6 4956.2 5100.7 5158.0 5165.8 5171.7 74667.0 75324.6 77432.6 78293.6 79071.6 68244.0 70156.3 71123.6 71656.6 72187.0 60954.4 61090.0 61196.0 61338.4 62110.2 10085.9 10115.9 10197.7 10385.3 10437.7 42908.0 43204.6 44074.6 44346.0 44388.3 50167.7 50197.2 50400.5 50466.5 50533.2 12282.3 12363.8 12368.1 12581.8 12622.7 13536.2 13604.9 13641.1 13669.8 13735.2 12438.9 12482.9 12569.3 12643.1 12659.9 12547.5 12676.5 12824.0 13172.2 13375.8 12929.5 13348.3 13405.4 13421.2 13843.3 10249.5 10548.0 10844.8 10846.2 11126.1 10434.8 10506.8 10527.7 10554.5 10662.5 09:02:30 INFO - PID 19167 | -------- Summary: end -------- 09:02:30 INFO - PID 19167 | 09:02:31 INFO - TEST-INFO | 19167: exit 0 09:02:31 INFO - TEST-OK | dromaeo_css | took 1116556ms 09:02:31 INFO - TEST-START | kraken 09:02:31 INFO - operating with platform_type : linux_ 09:02:31 INFO - Initialising browser for kraken test... 09:02:31 INFO - TEST-INFO | started process 19495 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCosOQX/profile http://localhost:36840/getInfo.html) 09:02:35 INFO - PID 19495 | __metrics Screen width/height:1600/1200 09:02:35 INFO - PID 19495 | colorDepth:24 09:02:35 INFO - PID 19495 | Browser inner width/height: 1024/697 09:02:35 INFO - PID 19495 | __metrics 09:02:36 INFO - PID 19495 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:02:36 INFO - PID 19495 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:02:37 INFO - TEST-INFO | 19495: exit 0 09:02:37 INFO - Browser initialized. 09:02:37 INFO - Running cycle 1/1 for kraken test... 09:02:37 INFO - TEST-INFO | started process 19589 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCosOQX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:48 INFO - PID 19589 | RSS: Main: 144080896 09:02:48 INFO - PID 19589 | 09:02:51 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36840/tests/kraken/driver.html?testName=audio-beat-detection) 09:02:52 INFO - PID 19589 | RSS: Main: 198782976 09:02:52 INFO - PID 19589 | 09:02:55 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36840/tests/kraken/driver.html?testName=audio-dft) 09:02:55 INFO - PID 19589 | RSS: Main: 179621888 09:02:55 INFO - PID 19589 | 09:02:58 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36840/tests/kraken/driver.html?testName=audio-fft) 09:02:59 INFO - PID 19589 | RSS: Main: 261808128 09:02:59 INFO - PID 19589 | 09:03:01 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36840/tests/kraken/driver.html?testName=audio-oscillator) 09:03:01 INFO - PID 19589 | RSS: Main: 173068288 09:03:01 INFO - PID 19589 | 09:03:04 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36840/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:03:04 INFO - PID 19589 | RSS: Main: 175837184 09:03:04 INFO - PID 19589 | 09:03:07 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36840/tests/kraken/driver.html?testName=imaging-darkroom) 09:03:08 INFO - PID 19589 | RSS: Main: 214716416 09:03:08 INFO - PID 19589 | 09:03:11 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36840/tests/kraken/driver.html?testName=imaging-desaturate) 09:03:12 INFO - PID 19589 | RSS: Main: 226832384 09:03:12 INFO - PID 19589 | 09:03:15 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36840/tests/kraken/driver.html?testName=json-parse-financial) 09:03:15 INFO - PID 19589 | RSS: Main: 237416448 09:03:15 INFO - PID 19589 | 09:03:17 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36840/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:03:17 INFO - PID 19589 | RSS: Main: 177631232 09:03:17 INFO - PID 19589 | 09:03:19 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36840/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:03:20 INFO - PID 19589 | RSS: Main: 184881152 09:03:20 INFO - PID 19589 | 09:03:22 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36840/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:03:22 INFO - PID 19589 | RSS: Main: 193122304 09:03:22 INFO - PID 19589 | 09:03:25 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36840/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:03:26 INFO - PID 19589 | RSS: Main: 202878976 09:03:26 INFO - PID 19589 | 09:03:29 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36840/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:03:29 INFO - PID 19589 | RSS: Main: 199352320 09:03:29 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | Cycle 1(1): loaded http://localhost:36840/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36840/tests/kraken/driver.html?testName=ai-astar) 09:03:31 INFO - PID 19589 | RSS: Main: 268152832 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | __start_tp_report 09:03:31 INFO - PID 19589 | _x_x_mozilla_page_load 09:03:31 INFO - PID 19589 | _x_x_mozilla_page_load_details 09:03:31 INFO - PID 19589 | |i|pagename|runs| 09:03:31 INFO - PID 19589 | |0;ai-astar;105;112;113;113;112;113;113;113;113;114 09:03:31 INFO - PID 19589 | |1;audio-beat-detection;151;118;118;168;124;124;119;124;148;123 09:03:31 INFO - PID 19589 | |2;audio-dft;161;163;161;162;161;162;163;163;162;161 09:03:31 INFO - PID 19589 | |3;audio-fft;74;74;75;112;74;74;76;74;103;76 09:03:31 INFO - PID 19589 | |4;audio-oscillator;84;82;101;84;83;83;110;84;83;111 09:03:31 INFO - PID 19589 | |5;imaging-gaussian-blur;112;112;113;113;113;113;113;113;113;113 09:03:31 INFO - PID 19589 | |6;imaging-darkroom;148;148;147;147;147;147;147;147;147;146 09:03:31 INFO - PID 19589 | |7;imaging-desaturate;94;94;94;95;94;94;94;94;94;94 09:03:31 INFO - PID 19589 | |8;json-parse-financial;59;57;57;59;58;62;59;58;57;60 09:03:31 INFO - PID 19589 | |9;json-stringify-tinderbox;56;56;55;56;56;56;56;56;57;56 09:03:31 INFO - PID 19589 | |10;stanford-crypto-aes;79;81;79;79;79;79;80;80;79;78 09:03:31 INFO - PID 19589 | |11;stanford-crypto-ccm;131;136;139;125;128;124;125;125;181;132 09:03:31 INFO - PID 19589 | |12;stanford-crypto-pbkdf2;190;183;180;186;181;183;190;189;175;188 09:03:31 INFO - PID 19589 | |13;stanford-crypto-sha256-iterative;64;64;63;63;65;64;64;66;65;63 09:03:31 INFO - PID 19589 | __end_tp_report 09:03:31 INFO - PID 19589 | __start_cc_report 09:03:31 INFO - PID 19589 | _x_x_mozilla_cycle_collect,1761 09:03:31 INFO - PID 19589 | __end_cc_report 09:03:31 INFO - PID 19589 | __startTimestamp1489421011609__endTimestamp 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | ------- Summary: start ------- 09:03:31 INFO - PID 19589 | Number of tests: 14 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.56 (2.3%) stddev-sans-first:0.60 09:03:31 INFO - PID 19589 | Values: 105.0 112.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 114.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:124.00 stddev:17.47 (14.1%) stddev-sans-first:17.07 09:03:31 INFO - PID 19589 | Values: 151.0 118.0 118.0 168.0 124.0 124.0 119.0 124.0 148.0 123.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:0.88 (0.5%) stddev-sans-first:0.87 09:03:31 INFO - PID 19589 | Values: 161.0 163.0 161.0 162.0 161.0 162.0 163.0 163.0 162.0 161.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#3] audio-fft Cycles:10 Average:81.20 Median:74.00 stddev:14.05 (19.0%) stddev-sans-first:14.65 09:03:31 INFO - PID 19589 | Values: 74.0 74.0 75.0 112.0 74.0 74.0 76.0 74.0 103.0 76.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#4] audio-oscillator Cycles:10 Average:90.50 Median:83.00 stddev:11.92 (14.4%) stddev-sans-first:12.41 09:03:31 INFO - PID 19589 | Values: 84.0 82.0 101.0 84.0 83.0 83.0 110.0 84.0 83.0 111.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 09:03:31 INFO - PID 19589 | Values: 112.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 09:03:31 INFO - PID 19589 | Values: 148.0 148.0 147.0 147.0 147.0 147.0 147.0 147.0 147.0 146.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:03:31 INFO - PID 19589 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.00 stddev:1.58 (2.7%) stddev-sans-first:1.67 09:03:31 INFO - PID 19589 | Values: 59.0 57.0 57.0 59.0 58.0 62.0 59.0 58.0 57.0 60.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#9] json-stringify-tinderbox Cycles:10 Average:56.00 Median:56.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 09:03:31 INFO - PID 19589 | Values: 56.0 56.0 55.0 56.0 56.0 56.0 56.0 56.0 57.0 56.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:79.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 09:03:31 INFO - PID 19589 | Values: 79.0 81.0 79.0 79.0 79.0 79.0 80.0 80.0 79.0 78.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#11] stanford-crypto-ccm Cycles:10 Average:134.60 Median:131.50 stddev:17.08 (13.0%) stddev-sans-first:18.07 09:03:31 INFO - PID 19589 | Values: 131.0 136.0 139.0 125.0 128.0 124.0 125.0 125.0 181.0 132.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.50 Median:187.00 stddev:4.97 (2.7%) stddev-sans-first:4.86 09:03:31 INFO - PID 19589 | Values: 190.0 183.0 180.0 186.0 181.0 183.0 190.0 189.0 175.0 188.0 09:03:31 INFO - PID 19589 | 09:03:31 INFO - PID 19589 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:0.99 (1.6%) stddev-sans-first:1.05 09:03:31 INFO - PID 19589 | Values: 64.0 64.0 63.0 63.0 65.0 64.0 64.0 66.0 65.0 63.0 09:03:31 INFO - PID 19589 | -------- Summary: end -------- 09:03:31 INFO - PID 19589 | 09:03:32 INFO - TEST-INFO | 19589: exit 0 09:03:32 INFO - TEST-OK | kraken | took 61126ms 09:03:32 INFO - SUITE-END | took 1177s 09:03:32 INFO - Completed test suite (00:19:37) 09:03:32 INFO - javascript benchmark 09:03:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7570.84855499736, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3210.39002589508, 3232.2242661761184, 3244.059222825381, 3280.7375409967203, 3285.540024396585, 5503.669779813204, 5603.2435621191125, 5616.0, 5618.466245706656, 5629.380768115504, 5514.228008078872, 5547.805826796063, 5549.056660367737, 5644.238027866237, 5687.7156142192825, 4989.077020751154, 5045.016221836743, 5064.493550644925, 5066.417362003362, 5082.618803589727, 4973.5772459341, 5007.4491805901325, 5031.547160755531, 5055.418626857903, 5061.519155680223, 4973.552380285755, 5015.3730783652045, 5056.443791182967, 5063.974680126613, 5118.0, 4993.400791904958, 5021.046001259749, 5022.296878437021, 5026.648134630577, 5029.698218106925, 4991.126552853236, 4994.775235114393, 4998.350214472095, 5002.349694539723, 5006.4492905780335, 5499.862503437406, 5503.532199763025, 5522.723863806794, 5553.666779993213, 5566.443355664442, 5954.404559544054, 5963.821085367446, 5965.761369545213, 5965.850853728648, 5967.761289548413, 5872.794452194153, 5959.523238140939, 6006.279246490405, 6022.909656355158, 6025.6987150642735, 5495.313085864267, 5495.972520137414, 5496.80761173361, 5499.312585926759, 5505.944940550583, 5951.345352011275, 5997.7001149942325, 6001.789937352171, 6005.519558435315, 6015.248093988252, 5767.250257466587, 5786.450287222707, 5858.2677165354335, 5914.9112763308585, 5941.22764040672, 6026.246719160105, 6028.758849645965, 6082.75668973245, 6087.117367981707, 6097.908531372033, 6004.459598636144, 6004.909926351108, 6009.1587177795145, 6009.939900601025, 6013.0, 5914.881702366015, 5983.0128028875615, 5988.640681559121, 6014.699265036731, 6029.8191054268445, 4583.702059366131, 4585.564371384713, 4589.082183563234, 4592.816287348535, 4593.954060459419, 4066.4510291110323, 4118.464599602033, 4126.339785634284, 4127.9174416511505, 4133.7106402551535, 5632.802851900164, 5650.7174641267775, 5653.604247702621, 5665.49010589049, 5702.201691763156, 6042.365551617105, 6048.274207095177, 6050.606710563799, 6050.939490605126, 6051.243594550681, 5493.258410114663, 5541.750621222055, 5542.75057622408, 5648.71756412186, 5656.066748986454, 5111.233315002779, 5123.410807757155, 5143.897122057613, 5156.381234251914, 5228.346456692913, 6129.96935015331, 6154.3845615438995, 6164.907526387018, 6172.660503672251, 6180.783672571439, 5937.465628093492, 5948.613340132878, 5953.404659534012, 5960.1357803117435, 6021.096835474538, 6104.511639068775, 6139.614800607318, 6147.477464415575, 6166.722497487534, 6190.41191086846, 5881.117832325185, 5886.323072846678, 5887.4406931341455, 5888.793892213752, 5891.882162356815, 5885.793997210077, 5948.910766338422, 5985.760569577343, 5995.700214989321, 5996.580239383201, 5872.031114865999, 5913.408659134052, 5920.437558431942, 5922.318933322723, 5931.347551769388, 5839.678817665069, 5853.795117171049, 5856.94143058581, 5857.121431785266, 5904.793332233351, 5833.67914764692, 5836.124581312667, 5846.035404158266, 5847.502962248086, 5852.005159123045, 5823.242978412779, 5831.271091113611, 5881.7647294107755, 5892.381299963442, 5892.499137573182, 5687.5734319927, 5714.942850571608, 5721.942780572307, 5723.484886360247, 5724.74238659253], "value": 5500.2509590121, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.6563546404118, 784.3920961255028, 841.7096101844861, 850.8851305073813, 854.7478493844319, 36104.819475902616, 37026.07434814128, 37204.25591488172, 37243.255134897285, 37283.440748388726, 38603.806980965055, 38997.0, 39006.21987560247, 39056.60943390565, 39060.80469597655, 7146.32109949554, 7278.9272107278775, 8672.566371681438, 8775.95612021939, 8844.0, 9816.50917454128, 10024.548895299693, 10028.298019138663, 10045.89954100457, 10054.849177262311, 10260.846087308697, 10334.483275836215, 10335.0, 10338.32800867945, 10338.638147664833, 10221.693349199535, 10250.641227557075, 10275.126614237799, 10287.948560257151, 10303.742406439898, 10232.795344093076, 10363.533640986174, 10378.532966016548, 10381.197076789225, 10386.324888882275, 40227.39658905119, 40520.79739601283, 40557.59442405568, 40607.18785624301, 40611.390829137585, 3714.145746478298, 3906.160175562268, 3922.17634296798, 3929.057026313693, 3951.4072889066583, 25473.23580292594, 25579.744202557922, 25740.613890791654, 25766.355841103934, 26472.60291095635, 3954.703397245218, 3976.3240249157425, 4031.5766844481495, 4042.2521833460905, 4079.571644977294, 183814.0, 184832.07583961994, 186655.06672466686, 186773.0, 187586.0, 37672.24655506875, 38309.42535861964, 45815.54184458113, 46470.30294545584, 46651.46879436882, 48899.42058980792, 48935.5106448931, 49147.11940328357, 49163.50836491661, 49433.25850112251, 14616.707665846623, 14708.0, 14727.705445891022, 14747.188904610135, 14781.778273325908, 11372.658820235407, 11468.0, 11525.88474115265, 11634.883651163549, 11666.88333116658, 14410.495632652806, 14463.49377771773, 14469.710605788036, 14544.563663090124, 14662.63343416423, 5724.4561766632105, 5732.140178973187, 5758.165066065397, 5766.452187042224, 5843.620164689281, 950.4344914775697, 959.1128206409184, 977.1791694976254, 982.1847866271121, 991.7074463033423, 24677.506449870903, 24693.382665433506, 24738.381540461633, 24898.253052408458, 24996.625050624254, 1022.4683164754287, 1023.4319952426475, 1026.4200726589454, 1034.9586016559253, 1059.8304271316551, 12665.240085594894, 13018.0, 13220.272884991226, 13233.0, 13469.663258418423, 327049.0, 327417.0, 328315.0, 328517.0, 329609.0, 52126.0, 52409.47590524046, 52544.21183682248, 52589.0, 52663.73668131712, 1378.81386012889, 1420.4389266239777, 1426.0588011912296, 1440.4310297432664, 1450.840407555168, 24102.51794964126, 26383.60424593594, 29361.55957660594, 29681.0, 30061.549076764302, 51927.740361297954, 52401.47598523966, 52495.475045249055, 52575.0, 52576.474235257156, 343729.0, 346143.0, 348429.0, 349077.0, 349288.0, 26292.474150517264, 29528.409431811673, 31356.529652055695, 31479.52780708337, 34936.30127397387, 37372.25255494929, 38170.809145954096, 39293.41059884161, 40322.59677403188, 40915.181696366504, 33919.0, 33919.491207631414, 34066.65933340635, 34186.65813341934, 34552.0, 33435.33129337351, 33788.83105584457, 33805.492917606745, 33945.49081763726, 34218.0, 34589.65410345864, 35613.46579801357, 35719.0, 35833.46249806307, 35845.82077089598, 28539.714602854536, 29929.401411972074, 30379.84810076024, 30709.0, 30751.2312192197, 24528.87735561311, 24700.25899223026, 24726.629100563867, 29604.259893502836, 30189.245268868453], "value": 18001.886245445596, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.6894019254692, 393.8503368719887, 398.92420440116393, 403.4029636138513, 426.12431453363325, 20080.29718959837, 20503.897480512576, 20792.584148317044, 20808.0, 21275.680864787042, 20254.08856601453, 20453.0, 20462.18151273951, 21051.789482105214, 21215.151393944223, 6542.41118299353, 6750.1562304711915, 6829.112215411989, 6833.043373927654, 6833.453323734114, 6492.350764923493, 6654.966725166368, 6682.6658667066695, 6728.663566821664, 6789.049533065375, 6730.697118629649, 6744.2244141923575, 6751.493637977147, 6751.797446076625, 6753.189617245913, 6511.251206111287, 6713.731450741964, 6727.428168605675, 6735.966320168368, 6741.764038258662, 6296.716647750863, 6342.849438471542, 6361.300256971729, 6563.606183628997, 6564.376384243489, 24530.632040519406, 24913.01739652077, 25002.61065445371, 25068.122615708544, 25306.240812775646, 2783.4572258409617, 2894.2908987298038, 2894.52240380337, 2897.1453421240785, 2914.4608247474284, 14430.134191948411, 14858.479953201586, 15123.848761512354, 15278.083315001024, 15498.922505387514, 2177.3032629558547, 2300.344401845466, 2314.409825494505, 2408.8675122868235, 2442.255112190781, 103858.48070759598, 105823.0, 106164.46917765362, 106365.0, 107873.0, 18486.0, 18518.722219166724, 18678.626427471376, 18832.0, 19061.332853350064, 33272.33821294291, 33543.142538607884, 33601.32797344039, 33671.0, 33867.830660846535, 9730.610775568897, 9838.704838854846, 10032.64885728996, 10052.195824334036, 10079.64721234768, 7954.761357159295, 8010.3191228745045, 8164.387670924704, 8175.754727358189, 8224.588770561448, 9553.379030363005, 9627.470489123025, 9780.266480014028, 9919.454430006275, 10223.428231613789, 3865.4723745044294, 3955.841766329372, 4058.776767277769, 4082.9183416331507, 4118.398977390746, 859.7506723050245, 881.1803625513295, 883.4831623500324, 889.9688510902217, 914.4558987402485, 20423.387298381072, 21249.68125478119, 21423.78576214218, 21521.601095928716, 21704.457388565414, 890.7149712092198, 899.1772528136812, 911.3848152497038, 917.4816228830689, 920.4385324951774, 11030.28303160292, 12009.819852702216, 13339.399727012307, 13710.52013179534, 14199.0, 617533.0, 618191.0, 618267.0, 622881.0, 622980.0, 107070.0, 109922.45038774915, 110502.44748776205, 112855.0, 113117.43441282905, 1312.1274352555633, 1316.0655934286572, 1362.2779926638898, 1366.3373263966969, 1366.7357694132888, 19430.61138777188, 19545.218191272757, 20108.59782804365, 20245.898770506054, 20309.89845050765, 49986.0, 51016.234756479425, 51188.74405627948, 51463.0, 51924.74037629788, 65277.0, 65419.0, 66770.33229667836, 67267.32732672806, 69135.65432172807, 11615.477303521531, 11991.481788824096, 12021.939890300493, 12236.877631223573, 12477.812832807696, 12786.424610892718, 13778.793318100035, 13790.241536715577, 14104.717905642035, 14649.26753662334, 11631.069514438994, 11806.232594881305, 12580.182288151242, 12856.678583035498, 12927.5475358362, 11502.0, 11584.710382240175, 11713.824292635787, 11928.554646297314, 12392.690182745504, 11681.474333655176, 11736.237144585575, 12775.744485110432, 13015.023873209358, 13107.868921310665, 12200.450979705756, 12229.14395992272, 12301.569445069379, 12504.249745015328, 12716.237025778113, 12254.693632659255, 12264.32546209967, 12363.62909112728, 12700.110992230824, 12753.872461275269], "value": 11287.111486125765, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.9513867014657, 1501.1968596800714, 1516.5981015031025, 1525.9160746158964, 1585.865201457876, 8972.551372431377, 10214.131798797102, 10678.145748340134, 10691.83962240567, 10702.250842441013, 10386.27296089274, 10545.47272636367, 10571.0, 10577.206709496799, 10587.311824731407, 1821.5810363616376, 2083.57286756215, 2134.125008746414, 2136.327056977047, 2136.359092272312, 1909.9045047747557, 2114.534802343482, 2147.5168087180336, 2174.3694328644674, 2178.7930146636045, 2080.4070839810656, 2106.1259577275487, 2109.7679255281905, 2123.543438160795, 2128.957420851582, 2050.866693664907, 2054.0551346380685, 2054.989725051365, 2062.3297428335777, 2066.5763518478675, 1986.3147214211074, 2025.6961455781604, 2029.0159272752703, 2093.1418118571464, 2096.9790302096935, 11001.22991390603, 11071.0, 11096.05683516902, 11096.61161859331, 11103.000729934345, 4949.208126699711, 5127.48725127488, 5131.846044618667, 5134.255532947739, 5139.203423469368, 3000.354923691417, 3106.8135911845134, 3183.363327334542, 3183.761217908643, 3244.9675503244903, 1132.6947519125818, 1234.382808595702, 1243.7636848998752, 1260.0864373329264, 1297.7015286484161, 15611.531654050397, 15974.920125399414, 15995.0, 16022.118783466905, 16060.919695401448, 4163.625273725379, 4278.850240241639, 4306.289462238696, 4323.63249123828, 4330.870073897788, 11515.0, 11618.07055435563, 11635.301881887113, 11635.709107272216, 11650.0, 5161.870953226125, 5206.687598744087, 5212.765425555859, 5213.400458947193, 5221.6083793715625, 4207.852725154605, 4329.826806927687, 4523.276275795923, 4621.584057434847, 4829.855104346875, 3438.5014172944784, 3459.4291941829315, 3522.18989632386, 3524.22467057247, 3551.7691350062164, 914.0448231597996, 922.1930810540777, 925.9027802080819, 931.7437704631145, 932.883389576303, 692.6571347183076, 695.1276148433683, 714.1822613107962, 725.0791494801573, 738.6971341749856, 6218.689065546795, 6284.24589049317, 6333.841653958688, 6402.743890244431, 6617.0, 1025.7435641089728, 1027.7379268286538, 1043.186314674555, 1061.4109169410897, 1064.69123954054, 4007.1184339445276, 4063.7358571692744, 4082.5509193988637, 4085.5914408558906, 4097.119119389286, 12909.0, 13276.070675052846, 13324.600261992156, 13333.266670333225, 13344.5329413472, 12965.611031669065, 13079.476820927246, 13101.672458188621, 13115.08194426381, 13115.737685246242, 8682.739517814476, 9005.729828105168, 9034.2320902724, 9562.18721408688, 9722.708318750449, 6184.5980011299125, 6274.811755647155, 6400.55196136266, 6414.102025716496, 6518.2504012037325, 11494.0, 11625.12811539121, 11719.355435451133, 11845.881541184477, 11902.166848320534, 11803.468843902217, 11862.644120676394, 11871.109666775199, 11998.76002479963, 12085.214461060003, 7776.92223077762, 8061.959690201512, 9141.63433462654, 9361.297902657192, 9390.530473476163, 10128.746781330527, 10324.277300588887, 10371.636992705218, 10373.066424021874, 10389.168866490814, 8387.95806020966, 8442.071372148936, 8479.872801908099, 8515.105913878953, 8527.232549070613, 8584.313254939465, 8658.090900455361, 8777.210051095177, 8991.0, 8997.685081022133, 7852.725154619561, 8000.479968802009, 8002.51984880909, 8002.839943200987, 8014.118446970712, 5691.544676425793, 5768.163616275533, 5814.622049566765, 5846.56150788684, 6021.87956240864, 5648.943510564842, 5891.705414729161, 5925.792597259076, 5937.732802023833, 6009.158717779602], "value": 4828.83356950575, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.67933954487535, 398.4043854437616, 404.5347849972533, 406.4431728531911, 409.0305974839629, 5627.352854421738, 5743.483086522207, 5753.827385178441, 5772.538196944245, 5780.942190578104, 5503.944960550403, 5523.530499907512, 5540.196671482643, 5683.602147849652, 5697.230873832032, 4463.620592249663, 4496.932546011821, 4500.257457519506, 4519.932201016971, 4531.705439146462, 4514.255147900592, 4528.547145285462, 4546.454425468932, 4546.522615125414, 4555.385023021883, 4384.671149663772, 4415.410452237192, 4476.776161191944, 4505.098980203956, 4531.864044078667, 4472.485664148599, 4472.5303843096335, 4481.350204220386, 4484.080763443486, 4488.798004089824, 4048.109415928491, 4064.2074795414906, 4066.694997875142, 4068.3083875741195, 4090.6931980101313, 5275.594878276605, 5542.63972841764, 5552.41699621542, 5566.86082847928, 5587.720613969326, 5472.863178420532, 5475.0, 5507.697076660782, 5513.972430137864, 5526.088195447746, 5970.164177015221, 6013.75944962201, 6042.697865106771, 6044.788432404889, 6062.636241825505, 4672.2057250267535, 4814.724098114003, 4820.469748327681, 4836.733979631119, 4905.460399356068, 5988.371221021774, 6165.811137046328, 6210.65841378724, 6274.654893980829, 6330.417395651088, 4606.355110284563, 4881.096997055556, 4892.633052521075, 4904.4605093439695, 4912.189488734391, 4956.405231372259, 5035.672681275779, 5058.468860769566, 5075.695458272516, 5103.642745007888, 5439.456054394529, 5465.426130256265, 5479.726013699299, 5486.91769623456, 5500.119980803052, 5888.852778680482, 5965.194698715704, 6010.879782404328, 6015.984645143838, 6029.939700603026, 1758.8241175882565, 1768.4694591622463, 1774.8935063896208, 1780.9910950445164, 1782.5900042990186, 1294.411042975444, 1334.4662135145825, 1357.7827547592337, 1358.6209996853202, 1371.5748118083427, 5713.714314284269, 5721.370649228582, 5728.627639203439, 5746.649537358718, 5761.4526619971875, 5954.672493012926, 6130.478909292671, 6172.506199504029, 6184.752609895644, 6203.31763506014, 5717.142428635738, 5728.398518155617, 5738.655680659173, 5757.712114394263, 5760.07838745807, 3154.7003034711665, 3253.6746325367276, 3279.9016029518675, 3283.195617073832, 3284.4088064148204, 6369.840753981095, 6379.74481020754, 6382.393672601095, 6396.456301214356, 6401.135846660642, 6129.0, 6320.367963203643, 6322.936770632235, 6325.240971083499, 6360.968195159087, 6011.316831287247, 6322.968385158045, 6337.207849018872, 6355.618662880242, 6356.459700925473, 5952.851178720567, 6012.248468941382, 6033.0136582392, 6035.728392222449, 6050.485708714633, 6009.789657361971, 6046.425589568984, 6056.242969628796, 6074.544409169418, 6077.574569780073, 6195.318514963441, 6280.309165991646, 6308.306086330408, 6325.703230837576, 6426.775062872777, 5012.749362531932, 5023.321851549994, 5029.371328583927, 5032.521910418504, 5048.949510504848, 5962.2249107615735, 6177.4749146321265, 6181.165542651865, 6201.224846894138, 6201.503879689524, 5967.492763115184, 5986.820395388146, 5988.700564971821, 6014.097885317237, 6037.577369584263, 5904.3800400959, 5934.881302374015, 5934.970325148347, 5938.8218353449465, 5966.134910437875, 5800.651960882361, 5927.2590926134235, 5951.702414879325, 6010.398960104129, 6018.157457955802, 6011.819645410645, 6026.909596356146, 6029.909551356645, 6037.607555508878, 6053.394660534088, 5729.4843464088435, 5786.884262314814, 5799.884002319845, 5899.495628614676, 5911.379305172895], "value": 4727.658843135369, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.0865057605586, 758.6244808819636, 796.1560745609654, 835.5696816139687, 863.7797361672773, 15244.237788110591, 15411.92294038531, 15771.526854194379, 15881.205939703012, 16021.599460013505, 14924.253787310645, 15062.924685376558, 15081.62295942602, 15087.471938482155, 16604.83395166051, 836.1429534726923, 851.3699862102031, 856.4946681457939, 856.9571521423935, 857.014433401587, 811.1442428238206, 840.0381563110266, 845.8181490979438, 846.5047946951041, 847.076686411811, 884.7257350221452, 890.5013192612125, 891.1712107739831, 891.6700820696351, 893.1425831202054, 877.0615441477589, 879.0725784297564, 880.7533890510667, 883.5582208895552, 884.5975081337975, 646.7994921574327, 649.9415052645285, 650.0, 650.39513252675, 650.6161364794793, 14493.492727754478, 15127.924360378238, 15330.923345383313, 15514.456994005155, 15564.455244066403, 14860.182689952047, 15357.385704571803, 15766.684666306724, 16254.918725406416, 16434.589135271595, 11713.70715732105, 11796.941015294955, 11919.404029798563, 12050.337231452266, 12146.45340959659, 3735.62643735627, 3778.3199024175638, 3802.524684414448, 3854.8650797222244, 3936.960630393688, 85304.65949820823, 88588.5570572143, 89037.0, 89552.55223723906, 91022.0, 12582.119251652297, 12615.116941814169, 12687.740176025784, 12788.744225115446, 12924.999999999813, 25601.48797024049, 25623.487530249662, 26002.0, 26284.21147365582, 26323.73676263251, 8314.6674133034, 8523.74428767138, 8621.224089831945, 8684.739457816275, 8768.0, 4094.877153685394, 4166.250074986531, 4242.406063151161, 4257.63810076147, 4273.529911709709, 3539.5398598182073, 3601.2077342984503, 3635.763675361093, 3636.709063274932, 3642.3625865473437, 561.9662820230799, 562.4853259267805, 565.5475619504463, 581.1573218832625, 583.6323116436618, 754.6905768634833, 768.9577073261024, 770.691723310669, 810.1048341582584, 811.2941740685641, 10226.795464090677, 10485.423301718478, 10742.516586753614, 11639.47622356996, 11713.765724685458, 749.0786332810674, 761.3300295739714, 771.4638326363236, 772.4978763803573, 779.5712358202966, 4956.231784073402, 5100.744962751847, 5158.045761534053, 5165.845024649267, 5171.741412929338, 74667.0, 75324.62337688386, 77432.61283693546, 78293.60853195812, 79071.60464197642, 68244.0, 70156.298437016, 71123.6443817788, 71656.64171679213, 72187.0, 60954.39045609487, 61090.0, 61196.0, 61338.38661613416, 62110.23023783553, 10085.899141008496, 10115.898841011496, 10197.74505637365, 10385.324953877975, 10437.68686939393, 42908.0, 43204.56795432005, 44074.559254407046, 44346.0, 44388.33417498805, 50167.74916125542, 50197.24704129368, 50400.49599504105, 50466.486005419894, 50533.242001370745, 12282.263064216175, 12363.75272494563, 12368.134230603771, 12581.811272830731, 12622.684432888884, 13536.187828730308, 13604.863951360756, 13641.113327633671, 13669.794953075512, 13735.175889446666, 12438.937805310916, 12482.875171248534, 12569.308688022247, 12643.114981951543, 12659.873401266237, 12547.498100075894, 12676.492940282655, 12824.0, 13172.209667419986, 13375.799363009757, 12929.482820687068, 13348.332583370599, 13405.396757146147, 13421.157684630738, 13843.307834608027, 10249.538770755185, 10548.0, 10844.837327440253, 10846.186536009673, 11126.109911207246, 10434.843477347998, 10506.789864202827, 10527.73680657959, 10554.525046372779, 10662.520186591466], "value": 7380.021689830504, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1508.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 112.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 118.0, 168.0, 124.0, 124.0, 119.0, 124.0, 148.0, 123.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 162.0, 161.0, 162.0, 163.0, 163.0, 162.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 112.0, 74.0, 74.0, 76.0, 74.0, 103.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 101.0, 84.0, 83.0, 83.0, 110.0, 84.0, 83.0, 111.0], "value": 90.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 57.0, 59.0, 58.0, 62.0, 59.0, 58.0, 57.0, 60.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0, 57.0, 56.0], "value": 56.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 79.0, 79.0, 79.0, 79.0, 80.0, 80.0, 79.0, 78.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 136.0, 139.0, 125.0, 128.0, 124.0, 125.0, 125.0, 181.0, 132.0], "value": 134.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 183.0, 180.0, 186.0, 181.0, 183.0, 190.0, 189.0, 175.0, 188.0], "value": 184.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 63.0, 65.0, 64.0, 64.0, 66.0, 65.0, 63.0], "value": 64.1, "unit": "score"}]}]} 09:03:32 INFO - Return code: 0 09:03:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:03:32 ERROR - # TBPL SUCCESS # 09:03:32 INFO - Running post-action listener: _resource_record_post_action 09:03:32 INFO - [mozharness: 2017-03-13 16:03:32.915201Z] Finished run-tests step (success) 09:03:32 INFO - Running post-run listener: _resource_record_post_run 09:03:32 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 367661056; Read time: 12; Write time: 246308 09:03:32 INFO - TinderboxPrint: CPU usage
12.9% 09:03:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:03:32 INFO - TinderboxPrint: I/O write bytes / time
367,661,056 / 246,308 09:03:32 INFO - TinderboxPrint: CPU idle
8,306.7 (87.1%) 09:03:32 INFO - TinderboxPrint: CPU user
1,187.3 (12.4%) 09:03:32 INFO - TinderboxPrint: Swap in / out
0 / 0 09:03:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111321088; Read time: 0; Write time: 106664 09:03:33 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 256339968; Read time: 12; Write time: 139644 09:03:33 INFO - Running post-run listener: _upload_blobber_files 09:03:33 INFO - Blob upload gear active. 09:03:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:03:33 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 09:03:33 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:03:33 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:03:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:03:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:03:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:03:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:34 INFO - (blobuploader) - INFO - Done attempting. 09:03:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:03:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:03:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:35 INFO - (blobuploader) - INFO - Done attempting. 09:03:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:03:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:03:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:39 INFO - (blobuploader) - INFO - Done attempting. 09:03:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:03:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:03:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:40 INFO - (blobuploader) - INFO - Done attempting. 09:03:40 INFO - (blobuploader) - INFO - Iteration through files over. 09:03:40 INFO - Return code: 0 09:03:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:03:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:03:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f972fad8291d34bdc1309e070ce9085a447bdc43c86a5b86d113854b3733533e9691e01e42df6916222c388ff18bef655a31a4509fb49b7094ad77fbfb052f2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ff14ae8bbed4cc9cfb7b7cb1bf33958a20c141c7634966c3355a11196758681e3eab623d54ec976b45c314bd1d301e0222a991807224e6f75c995297f8f8e11", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/918c9df8620c6eacc0bb578ddc8aa4d1111ed6dc31fe05d40550eff580c12001655d767e3cc4b716559664a05304ba2a950653a8bd389d4cb4ed000767006a74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45da2a2df9f2bb0219a9ae441b3286084caa915eca0f63bbe15b50ffe52d6a656f0b3f2a169fdce4274ebc7d54fb4e9c60263e5d5cdcacbf9ef2542e0dfee906"} 09:03:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:03:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:03:40 INFO - Contents: 09:03:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f972fad8291d34bdc1309e070ce9085a447bdc43c86a5b86d113854b3733533e9691e01e42df6916222c388ff18bef655a31a4509fb49b7094ad77fbfb052f2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ff14ae8bbed4cc9cfb7b7cb1bf33958a20c141c7634966c3355a11196758681e3eab623d54ec976b45c314bd1d301e0222a991807224e6f75c995297f8f8e11", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/918c9df8620c6eacc0bb578ddc8aa4d1111ed6dc31fe05d40550eff580c12001655d767e3cc4b716559664a05304ba2a950653a8bd389d4cb4ed000767006a74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45da2a2df9f2bb0219a9ae441b3286084caa915eca0f63bbe15b50ffe52d6a656f0b3f2a169fdce4274ebc7d54fb4e9c60263e5d5cdcacbf9ef2542e0dfee906"} 09:03:40 INFO - Running post-run listener: copy_logs_to_upload_dir 09:03:40 INFO - Copying logs to upload dir... 09:03:40 INFO - mkdir: /builds/slave/test/build/upload/logs 09:03:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.661575 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-03-13 09:03:40.043037) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-13 09:03:40.046306) ========= 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=41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f972fad8291d34bdc1309e070ce9085a447bdc43c86a5b86d113854b3733533e9691e01e42df6916222c388ff18bef655a31a4509fb49b7094ad77fbfb052f2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ff14ae8bbed4cc9cfb7b7cb1bf33958a20c141c7634966c3355a11196758681e3eab623d54ec976b45c314bd1d301e0222a991807224e6f75c995297f8f8e11", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/918c9df8620c6eacc0bb578ddc8aa4d1111ed6dc31fe05d40550eff580c12001655d767e3cc4b716559664a05304ba2a950653a8bd389d4cb4ed000767006a74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45da2a2df9f2bb0219a9ae441b3286084caa915eca0f63bbe15b50ffe52d6a656f0b3f2a169fdce4274ebc7d54fb4e9c60263e5d5cdcacbf9ef2542e0dfee906"} build_url:https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011489 build_url: 'https://queue.taskcluster.net/v1/task/b7YI6CuUSbKJJTmiSxG3Og/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f972fad8291d34bdc1309e070ce9085a447bdc43c86a5b86d113854b3733533e9691e01e42df6916222c388ff18bef655a31a4509fb49b7094ad77fbfb052f2e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ff14ae8bbed4cc9cfb7b7cb1bf33958a20c141c7634966c3355a11196758681e3eab623d54ec976b45c314bd1d301e0222a991807224e6f75c995297f8f8e11", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/918c9df8620c6eacc0bb578ddc8aa4d1111ed6dc31fe05d40550eff580c12001655d767e3cc4b716559664a05304ba2a950653a8bd389d4cb4ed000767006a74", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45da2a2df9f2bb0219a9ae441b3286084caa915eca0f63bbe15b50ffe52d6a656f0b3f2a169fdce4274ebc7d54fb4e9c60263e5d5cdcacbf9ef2542e0dfee906"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-13 09:03:40.071033) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-13 09:03:40.071347) ========= 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=41bf3e9aed84707956354da8000001c5-1489419764.547420-1379546447 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 2017-03-13 09:03:40.155937) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-13 09:03:40.156248) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-13 09:03:40.156538) ========= ========= Total master_lag: 0.16 =========