builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-050 starttime: 1474462338.24 results: success (0) buildid: 20160921030221 builduid: 3de2c328b48f4de7a143e759679950e8 revision: e2d2897e4a7449759267bf168e6b37f7b8c3a94b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:18.236178) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:18.236726) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:18.237051) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:18.252160) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:18.252472) ========= 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=6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 05:52:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2016-09-21 05:52:18 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121457 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:18.395551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:18.396893) ========= 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=6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020457 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:18.448565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 05:52:18.448983) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e2d2897e4a7449759267bf168e6b37f7b8c3a94b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev e2d2897e4a7449759267bf168e6b37f7b8c3a94b --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=6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 05:52:19,057 truncating revision to first 12 chars 2016-09-21 05:52:19,057 Setting DEBUG logging. 2016-09-21 05:52:19,057 attempt 1/10 2016-09-21 05:52:19,057 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e2d2897e4a74?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 05:52:20,447 unpacking tar archive at: mozilla-central-e2d2897e4a74/testing/mozharness/ program finished with exit code 0 elapsedTime=1.735203 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 05:52:20.198518) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:20.198943) ========= script_repo_revision: e2d2897e4a7449759267bf168e6b37f7b8c3a94b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:20.199419) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:20.199751) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 05:52:20.209879) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-21 05:52:20.210211) ========= /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=6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:52:20 INFO - MultiFileLogger online at 20160921 05:52:20 in /builds/slave/test 05:52:20 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 05:52:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:52:20 INFO - {'append_to_log': False, 05:52:20 INFO - 'base_work_dir': '/builds/slave/test', 05:52:20 INFO - 'blob_upload_branch': 'Firefox', 05:52:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:52:20 INFO - 'branch': 'Firefox', 05:52:20 INFO - 'buildbot_json_path': 'buildprops.json', 05:52:20 INFO - 'config_files': ('talos/linux_config.py',), 05:52:20 INFO - 'default_actions': ('clobber', 05:52:20 INFO - 'read-buildbot-config', 05:52:20 INFO - 'download-and-extract', 05:52:20 INFO - 'populate-webroot', 05:52:20 INFO - 'create-virtualenv', 05:52:20 INFO - 'install', 05:52:20 INFO - 'run-tests'), 05:52:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:52:20 INFO - 'download_minidump_stackwalk': True, 05:52:20 INFO - 'download_symbols': 'ondemand', 05:52:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:52:20 INFO - 'tooltool.py': '/tools/tooltool.py', 05:52:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:52:20 INFO - '/tools/misc-python/virtualenv.py')}, 05:52:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:52:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:52:20 INFO - 'installer_path': 'installer.exe', 05:52:20 INFO - 'log_level': 'info', 05:52:20 INFO - 'log_name': 'talos', 05:52:20 INFO - 'log_to_console': True, 05:52:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:52:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:52:20 INFO - 'opt_config_files': (), 05:52:20 INFO - 'pip_index': False, 05:52:20 INFO - 'sps_profile': False, 05:52:20 INFO - 'sps_profile_interval': 0, 05:52:20 INFO - 'suite': 'dromaeojs', 05:52:20 INFO - 'system_bits': '32', 05:52:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:52:20 INFO - 'title': 'talos-linux64-ix-050', 05:52:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:52:20 INFO - 'use_talos_json': True, 05:52:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:52:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:52:20 INFO - 'work_dir': 'build'} 05:52:20 INFO - [mozharness: 2016-09-21 12:52:20.918852Z] Running clobber step. 05:52:20 INFO - Running pre-action listener: _resource_record_pre_action 05:52:20 INFO - Running main action method: clobber 05:52:20 INFO - rmtree: /builds/slave/test/build 05:52:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:52:21 INFO - Running post-action listener: _resource_record_post_action 05:52:21 INFO - [mozharness: 2016-09-21 12:52:21.391483Z] Finished clobber step (success) 05:52:21 INFO - [mozharness: 2016-09-21 12:52:21.391586Z] Running read-buildbot-config step. 05:52:21 INFO - Running pre-action listener: _resource_record_pre_action 05:52:21 INFO - Running main action method: read_buildbot_config 05:52:21 INFO - Using buildbot properties: 05:52:21 INFO - { 05:52:21 INFO - "project": "", 05:52:21 INFO - "product": "firefox", 05:52:21 INFO - "script_repo_revision": "production", 05:52:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:52:21 INFO - "repository": "", 05:52:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:52:21 INFO - "buildid": "20160921030221", 05:52:21 INFO - "stage_platform": "linux64-pgo", 05:52:21 INFO - "basedir": "/builds/slave/test", 05:52:21 INFO - "nightly_build": "True", 05:52:21 INFO - "buildnumber": 888, 05:52:21 INFO - "slavename": "talos-linux64-ix-050", 05:52:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:52:21 INFO - "platform": "ubuntu64_hw", 05:52:21 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:52:21 INFO - "branch": "mozilla-central", 05:52:21 INFO - "repo_path": "mozilla-central", 05:52:21 INFO - "revision": "e2d2897e4a7449759267bf168e6b37f7b8c3a94b", 05:52:21 INFO - "pgo_build": "True", 05:52:21 INFO - "builduid": "3de2c328b48f4de7a143e759679950e8", 05:52:21 INFO - "slavebuilddir": "test" 05:52:21 INFO - } 05:52:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 05:52:21 INFO - Running post-action listener: _resource_record_post_action 05:52:21 INFO - [mozharness: 2016-09-21 12:52:21.394151Z] Finished read-buildbot-config step (success) 05:52:21 INFO - [mozharness: 2016-09-21 12:52:21.394242Z] Running download-and-extract step. 05:52:21 INFO - Running pre-action listener: _resource_record_pre_action 05:52:21 INFO - Running main action method: download_and_extract 05:52:21 INFO - mkdir: /builds/slave/test/build/tests 05:52:21 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')]} 05:52:21 INFO - trying https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 05:52:21 INFO - Downloading https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 05:52:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:52:21 INFO - Downloaded 1361 bytes. 05:52:21 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 05:52:21 INFO - Using the following test package requirements: 05:52:21 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 05:52:21 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:52:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:52:21 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:52:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:52:21 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:52:21 INFO - u'jsshell-linux-x86_64.zip'], 05:52:21 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:52:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:52:21 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 05:52:21 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:52:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:52:21 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:52:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:52:21 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:52:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:52:21 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:52:21 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:52:21 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:52:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 05:52:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:52:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:52:22 INFO - Expected file size: 21941267 05:52:26 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:52:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 05:52:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:52:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:52:27 INFO - Expected file size: 21941267 05:52:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 05:52:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:52:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:52:32 INFO - Expected file size: 14414059 05:52: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')]} 05:52:33 INFO - trying https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 05:52:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 05:52:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:52:34 INFO - Downloaded 59597427 bytes. 05:52:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 05:52:34 INFO - mkdir: /builds/slave/test/properties 05:52:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:52:34 INFO - Writing to file /builds/slave/test/properties/build_url 05:52:34 INFO - Contents: 05:52:34 INFO - build_url:https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 05:52:34 INFO - Running post-action listener: _resource_record_post_action 05:52:34 INFO - Running post-action listener: set_extra_try_arguments 05:52:34 INFO - [mozharness: 2016-09-21 12:52:34.901646Z] Finished download-and-extract step (success) 05:52:34 INFO - [mozharness: 2016-09-21 12:52:34.901876Z] Running populate-webroot step. 05:52:34 INFO - Running pre-action listener: _resource_record_pre_action 05:52:34 INFO - Running main action method: populate_webroot 05:52:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:52:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:52:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:52:34 INFO - '--tppagecycles', 05:52:34 INFO - '7'], 05:52:34 INFO - 'tests': ['tsvgm']}}, 05:52:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:52:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:52:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:52:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:52:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:34 INFO - 'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:52:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:52:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:34 INFO - 'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['damp', 'tps']}, 05:52:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:34 INFO - 'tests': ['damp', 'tps']}, 05:52:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['dromaeo_dom']}, 05:52:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:52:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['basic_compositor_video']}, 05:52:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:52:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['a11yr', 05:52:34 INFO - 'ts_paint', 05:52:34 INFO - 'tpaint', 05:52:34 INFO - 'sessionrestore', 05:52:34 INFO - 'sessionrestore_no_auto_restore', 05:52:34 INFO - 'tabpaint']}, 05:52:34 INFO - 'other-e10s': {'tests': ['a11yr', 05:52:34 INFO - 'ts_paint', 05:52:34 INFO - 'tpaint', 05:52:34 INFO - 'sessionrestore', 05:52:34 INFO - 'sessionrestore_no_auto_restore', 05:52:34 INFO - 'tabpaint']}, 05:52:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:52:34 INFO - 'ts_paint', 05:52:34 INFO - 'tpaint', 05:52:34 INFO - 'sessionrestore', 05:52:34 INFO - 'sessionrestore_no_auto_restore', 05:52:34 INFO - 'tabpaint']}, 05:52:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:52:34 INFO - 'ts_paint', 05:52:34 INFO - 'tpaint', 05:52:34 INFO - 'sessionrestore', 05:52:34 INFO - 'sessionrestore_no_auto_restore', 05:52:34 INFO - 'tabpaint']}, 05:52:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['a11yr', 05:52:34 INFO - 'ts_paint', 05:52:34 INFO - 'tpaint', 05:52:34 INFO - 'sessionrestore', 05:52:34 INFO - 'sessionrestore_no_auto_restore', 05:52:34 INFO - 'tabpaint']}, 05:52:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['a11yr', 05:52:34 INFO - 'ts_paint', 05:52:34 INFO - 'tpaint', 05:52:34 INFO - 'sessionrestore', 05:52:34 INFO - 'sessionrestore_no_auto_restore', 05:52:34 INFO - 'tabpaint']}, 05:52:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['tsvgx', 05:52:34 INFO - 'tsvgr_opacity', 05:52:34 INFO - 'tart', 05:52:34 INFO - 'tscrollx', 05:52:34 INFO - 'cart']}, 05:52:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:52:34 INFO - 'tsvgr_opacity', 05:52:34 INFO - 'tart', 05:52:34 INFO - 'tscrollx', 05:52:34 INFO - 'cart']}, 05:52:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:34 INFO - 'talos_options': ['--disable-e10s'], 05:52:34 INFO - 'tests': ['tp5o']}, 05:52:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:34 INFO - 'tests': ['tp5o']}, 05:52:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:34 INFO - 'talos_options': ['--disable-e10s', 05:52:34 INFO - '--xperf_path', 05:52:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:52:34 INFO - 'tests': ['tp5n']}, 05:52:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:34 INFO - 'talos_options': ['--xperf_path', 05:52:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:52:34 INFO - 'tests': ['tp5n']}}, 05:52:34 INFO - 'talos.zip': {'path': '', 05:52:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:52:34 INFO - Running post-action listener: _resource_record_post_action 05:52:34 INFO - [mozharness: 2016-09-21 12:52:34.920591Z] Finished populate-webroot step (success) 05:52:34 INFO - [mozharness: 2016-09-21 12:52:34.920680Z] Running create-virtualenv step. 05:52:34 INFO - Running pre-action listener: _resource_record_pre_action 05:52:34 INFO - Running main action method: create_virtualenv 05:52:34 INFO - Creating virtualenv /builds/slave/test/build/venv 05:52:34 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 05:52:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:52:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:52:35 INFO - Using real prefix '/usr' 05:52:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:52:35 INFO - Installing distribute.............................................................................................................................................................................................done. 05:52:37 INFO - Installing pip.................done. 05:52:37 INFO - Return code: 0 05:52:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:52: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')]} 05:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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')]} 05:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52: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', 'pip>=1.5'] in /builds/slave/test/build 05:52: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 pip>=1.5 05:52:37 INFO - Using env: {'DISPLAY': ':0', 05:52:37 INFO - 'HOME': '/home/cltbld', 05:52:37 INFO - 'LANG': 'en_US.UTF-8', 05:52:37 INFO - 'LANGUAGE': 'en_US:en', 05:52:37 INFO - 'LOGNAME': 'cltbld', 05:52:37 INFO - 'MAIL': '/var/mail/cltbld', 05:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:37 INFO - 'MOZ_NO_REMOTE': '1', 05:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:37 INFO - 'NO_EM_RESTART': '1', 05:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:37 INFO - 'PWD': '/builds/slave/test', 05:52:37 INFO - 'SHELL': '/bin/bash', 05:52:37 INFO - 'SHLVL': '1', 05:52:37 INFO - 'TERM': 'linux', 05:52:37 INFO - 'TMOUT': '86400', 05:52:37 INFO - 'USER': 'cltbld', 05:52:37 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:37 INFO - '_': '/tools/buildbot/bin/python'} 05:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:37 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 05:52:37 INFO - Cleaning up... 05:52:37 INFO - Return code: 0 05:52:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:52: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')]} 05:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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')]} 05:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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', '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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:52: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 psutil>=3.1.1 05:52:37 INFO - Using env: {'DISPLAY': ':0', 05:52:37 INFO - 'HOME': '/home/cltbld', 05:52:37 INFO - 'LANG': 'en_US.UTF-8', 05:52:37 INFO - 'LANGUAGE': 'en_US:en', 05:52:37 INFO - 'LOGNAME': 'cltbld', 05:52:37 INFO - 'MAIL': '/var/mail/cltbld', 05:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:37 INFO - 'MOZ_NO_REMOTE': '1', 05:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:37 INFO - 'NO_EM_RESTART': '1', 05:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:37 INFO - 'PWD': '/builds/slave/test', 05:52:37 INFO - 'SHELL': '/bin/bash', 05:52:37 INFO - 'SHLVL': '1', 05:52:37 INFO - 'TERM': 'linux', 05:52:37 INFO - 'TMOUT': '86400', 05:52:37 INFO - 'USER': 'cltbld', 05:52:37 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:37 INFO - '_': '/tools/buildbot/bin/python'} 05:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:37 INFO - Downloading/unpacking psutil>=3.1.1 05:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:52:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:52:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:52:39 INFO - Installing collected packages: psutil 05:52:39 INFO - Running setup.py install for psutil 05:52:40 INFO - building 'psutil._psutil_linux' extension 05:52:40 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 05:52:40 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 05:52:40 INFO - building 'psutil._psutil_posix' extension 05:52:40 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 05:52:40 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 05:52:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:52:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:52:40 INFO - Successfully installed psutil 05:52:40 INFO - Cleaning up... 05:52:40 INFO - Return code: 0 05:52:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:40 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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52:40 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 05:52:40 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 05:52:40 INFO - Using env: {'DISPLAY': ':0', 05:52:40 INFO - 'HOME': '/home/cltbld', 05:52:40 INFO - 'LANG': 'en_US.UTF-8', 05:52:40 INFO - 'LANGUAGE': 'en_US:en', 05:52:40 INFO - 'LOGNAME': 'cltbld', 05:52:40 INFO - 'MAIL': '/var/mail/cltbld', 05:52:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:40 INFO - 'MOZ_NO_REMOTE': '1', 05:52:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:40 INFO - 'NO_EM_RESTART': '1', 05:52:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:40 INFO - 'PWD': '/builds/slave/test', 05:52:40 INFO - 'SHELL': '/bin/bash', 05:52:40 INFO - 'SHLVL': '1', 05:52:40 INFO - 'TERM': 'linux', 05:52:40 INFO - 'TMOUT': '86400', 05:52:40 INFO - 'USER': 'cltbld', 05:52:40 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:40 INFO - '_': '/tools/buildbot/bin/python'} 05:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:52:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:52:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:52:42 INFO - Installing collected packages: mozsystemmonitor 05:52:42 INFO - Running setup.py install for mozsystemmonitor 05:52:42 INFO - Successfully installed mozsystemmonitor 05:52:42 INFO - Cleaning up... 05:52:42 INFO - Return code: 0 05:52:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:52:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:52:42 INFO - Using env: {'DISPLAY': ':0', 05:52:42 INFO - 'HOME': '/home/cltbld', 05:52:42 INFO - 'LANG': 'en_US.UTF-8', 05:52:42 INFO - 'LANGUAGE': 'en_US:en', 05:52:42 INFO - 'LOGNAME': 'cltbld', 05:52:42 INFO - 'MAIL': '/var/mail/cltbld', 05:52:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:42 INFO - 'MOZ_NO_REMOTE': '1', 05:52:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:42 INFO - 'NO_EM_RESTART': '1', 05:52:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:42 INFO - 'PWD': '/builds/slave/test', 05:52:42 INFO - 'SHELL': '/bin/bash', 05:52:42 INFO - 'SHLVL': '1', 05:52:42 INFO - 'TERM': 'linux', 05:52:42 INFO - 'TMOUT': '86400', 05:52:42 INFO - 'USER': 'cltbld', 05:52:42 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:42 INFO - '_': '/tools/buildbot/bin/python'} 05:52:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:43 INFO - Downloading/unpacking jsonschema==2.5.1 05:52:43 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 05:52:43 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 05:52:43 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 05:52:43 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 05:52:43 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 05:52:43 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 05:52:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:52:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:52:44 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 05:52:44 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 05:52:44 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 05:52:44 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 05:52:44 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 05:52:44 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 05:52:45 INFO - Downloading functools32-3.2.3-2.tar.gz 05:52:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:52:45 INFO - warning: no files found matching '*.txt' 05:52:45 INFO - no previously-included directories found matching 'build' 05:52:45 INFO - no previously-included directories found matching 'dist' 05:52:45 INFO - no previously-included directories found matching '.git*' 05:52:45 INFO - Installing collected packages: jsonschema, functools32 05:52:45 INFO - Running setup.py install for functools32 05:52:45 INFO - warning: no files found matching '*.txt' 05:52:45 INFO - no previously-included directories found matching 'build' 05:52:45 INFO - no previously-included directories found matching 'dist' 05:52:45 INFO - no previously-included directories found matching '.git*' 05:52:45 INFO - Successfully installed jsonschema functools32 05:52:45 INFO - Cleaning up... 05:52:45 INFO - Return code: 0 05:52:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:52:45 INFO - Using env: {'DISPLAY': ':0', 05:52:45 INFO - 'HOME': '/home/cltbld', 05:52:45 INFO - 'LANG': 'en_US.UTF-8', 05:52:45 INFO - 'LANGUAGE': 'en_US:en', 05:52:45 INFO - 'LOGNAME': 'cltbld', 05:52:45 INFO - 'MAIL': '/var/mail/cltbld', 05:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:45 INFO - 'MOZ_NO_REMOTE': '1', 05:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:45 INFO - 'NO_EM_RESTART': '1', 05:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:45 INFO - 'PWD': '/builds/slave/test', 05:52:45 INFO - 'SHELL': '/bin/bash', 05:52:45 INFO - 'SHLVL': '1', 05:52:45 INFO - 'TERM': 'linux', 05:52:45 INFO - 'TMOUT': '86400', 05:52:45 INFO - 'USER': 'cltbld', 05:52:45 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:45 INFO - '_': '/tools/buildbot/bin/python'} 05:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:52:45 INFO - Cleaning up... 05:52:45 INFO - Return code: 0 05:52:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:52:45 INFO - Using env: {'DISPLAY': ':0', 05:52:45 INFO - 'HOME': '/home/cltbld', 05:52:45 INFO - 'LANG': 'en_US.UTF-8', 05:52:45 INFO - 'LANGUAGE': 'en_US:en', 05:52:45 INFO - 'LOGNAME': 'cltbld', 05:52:45 INFO - 'MAIL': '/var/mail/cltbld', 05:52:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:45 INFO - 'MOZ_NO_REMOTE': '1', 05:52:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:45 INFO - 'NO_EM_RESTART': '1', 05:52:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:45 INFO - 'PWD': '/builds/slave/test', 05:52:45 INFO - 'SHELL': '/bin/bash', 05:52:45 INFO - 'SHLVL': '1', 05:52:45 INFO - 'TERM': 'linux', 05:52:45 INFO - 'TMOUT': '86400', 05:52:45 INFO - 'USER': 'cltbld', 05:52:45 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:45 INFO - '_': '/tools/buildbot/bin/python'} 05:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:45 INFO - Downloading/unpacking blobuploader==1.2.4 05:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:47 INFO - Downloading blobuploader-1.2.4.tar.gz 05:52:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:52:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:52:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:52:48 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 05:52:48 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 05:52:48 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 05:52:48 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 05:52:48 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 05:52:48 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 05:52:48 INFO - Downloading docopt-0.6.1.tar.gz 05:52:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:52:48 INFO - Installing collected packages: blobuploader, requests, docopt 05:52:48 INFO - Running setup.py install for blobuploader 05:52:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:52:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:52:48 INFO - Running setup.py install for requests 05:52:48 INFO - Running setup.py install for docopt 05:52:49 INFO - Successfully installed blobuploader requests docopt 05:52:49 INFO - Cleaning up... 05:52:49 INFO - Return code: 0 05:52:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:52: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')]} 05:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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')]} 05:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:49 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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52:49 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 05:52:49 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 05:52:49 INFO - Using env: {'DISPLAY': ':0', 05:52:49 INFO - 'HOME': '/home/cltbld', 05:52:49 INFO - 'LANG': 'en_US.UTF-8', 05:52:49 INFO - 'LANGUAGE': 'en_US:en', 05:52:49 INFO - 'LOGNAME': 'cltbld', 05:52:49 INFO - 'MAIL': '/var/mail/cltbld', 05:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:49 INFO - 'MOZ_NO_REMOTE': '1', 05:52:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:49 INFO - 'NO_EM_RESTART': '1', 05:52:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:49 INFO - 'PWD': '/builds/slave/test', 05:52:49 INFO - 'SHELL': '/bin/bash', 05:52:49 INFO - 'SHLVL': '1', 05:52:49 INFO - 'TERM': 'linux', 05:52:49 INFO - 'TMOUT': '86400', 05:52:49 INFO - 'USER': 'cltbld', 05:52:49 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:49 INFO - '_': '/tools/buildbot/bin/python'} 05:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:52:49 INFO - Running setup.py (path:/tmp/pip-RONzSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:52:49 INFO - Running setup.py (path:/tmp/pip-svTKa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:52:49 INFO - Running setup.py (path:/tmp/pip-kMJvnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:52:49 INFO - Running setup.py (path:/tmp/pip-CS7whW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:52:49 INFO - Running setup.py (path:/tmp/pip-eTRTdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:52:49 INFO - Running setup.py (path:/tmp/pip-kK7mCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:52:49 INFO - Running setup.py (path:/tmp/pip-6ryPqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:52:49 INFO - Running setup.py (path:/tmp/pip-fihwrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:52:49 INFO - Running setup.py (path:/tmp/pip-Kik35e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:52:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:52:49 INFO - Running setup.py (path:/tmp/pip-PJMMOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:52:50 INFO - Running setup.py (path:/tmp/pip-flfEOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:52:50 INFO - Running setup.py (path:/tmp/pip-mkbTcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:52:50 INFO - Running setup.py (path:/tmp/pip-Y1hUU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:52:50 INFO - Running setup.py (path:/tmp/pip-ju0zIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:52:50 INFO - Running setup.py (path:/tmp/pip-Y5Q2XO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:52:50 INFO - Running setup.py (path:/tmp/pip-jEfhjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:52:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:52:50 INFO - Running setup.py (path:/tmp/pip-vWevyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:52:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:52:50 INFO - Running setup.py install for manifestparser 05:52:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:52:50 INFO - Running setup.py install for mozcrash 05:52:50 INFO - Running setup.py install for mozdebug 05:52:50 INFO - Running setup.py install for mozdevice 05:52:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:52:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:52:50 INFO - Running setup.py install for mozfile 05:52:51 INFO - Running setup.py install for mozhttpd 05:52:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Running setup.py install for mozinfo 05:52:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Running setup.py install for mozInstall 05:52:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Running setup.py install for mozleak 05:52:51 INFO - Running setup.py install for mozlog 05:52:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Running setup.py install for moznetwork 05:52:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Running setup.py install for mozprocess 05:52:51 INFO - Running setup.py install for mozprofile 05:52:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:52:51 INFO - Running setup.py install for mozrunner 05:52:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:52:52 INFO - Running setup.py install for mozscreenshot 05:52:52 INFO - Running setup.py install for moztest 05:52:52 INFO - Running setup.py install for mozversion 05:52:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:52:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:52:52 INFO - Cleaning up... 05:52:52 INFO - Return code: 0 05:52:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:52 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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52:52 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 05:52:52 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 05:52:52 INFO - Using env: {'DISPLAY': ':0', 05:52:52 INFO - 'HOME': '/home/cltbld', 05:52:52 INFO - 'LANG': 'en_US.UTF-8', 05:52:52 INFO - 'LANGUAGE': 'en_US:en', 05:52:52 INFO - 'LOGNAME': 'cltbld', 05:52:52 INFO - 'MAIL': '/var/mail/cltbld', 05:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:52 INFO - 'MOZ_NO_REMOTE': '1', 05:52:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:52 INFO - 'NO_EM_RESTART': '1', 05:52:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:52 INFO - 'PWD': '/builds/slave/test', 05:52:52 INFO - 'SHELL': '/bin/bash', 05:52:52 INFO - 'SHLVL': '1', 05:52:52 INFO - 'TERM': 'linux', 05:52:52 INFO - 'TMOUT': '86400', 05:52:52 INFO - 'USER': 'cltbld', 05:52:52 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:52 INFO - '_': '/tools/buildbot/bin/python'} 05:52:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:52:52 INFO - Running setup.py (path:/tmp/pip-jyjopq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:52:52 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)) 05:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:52:52 INFO - Running setup.py (path:/tmp/pip-iVUBuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:52:52 INFO - Running setup.py (path:/tmp/pip-jmbJmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:52:52 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)) 05:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:52:52 INFO - Running setup.py (path:/tmp/pip-hXmWJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:52:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:52:52 INFO - Running setup.py (path:/tmp/pip-KxUvad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:52:52 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)) 05:52:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:52:52 INFO - Running setup.py (path:/tmp/pip-NKxcSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:52:53 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)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:52:53 INFO - Running setup.py (path:/tmp/pip-hTixPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:52:53 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)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:52:53 INFO - Running setup.py (path:/tmp/pip-KoiDM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:52:53 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)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:52:53 INFO - Running setup.py (path:/tmp/pip-rvKH5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:52:53 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)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:52:53 INFO - Running setup.py (path:/tmp/pip-7cldaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:52:53 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)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:52:53 INFO - Running setup.py (path:/tmp/pip-jZPvdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:52:53 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)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:52:53 INFO - Running setup.py (path:/tmp/pip-o0Skxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:52:53 INFO - Running setup.py (path:/tmp/pip-PM2H7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:52:53 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)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:52:53 INFO - Running setup.py (path:/tmp/pip-edk7Pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:52:53 INFO - Running setup.py (path:/tmp/pip-pegcEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:52:53 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)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:52:53 INFO - Running setup.py (path:/tmp/pip-bPqPvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:52:53 INFO - Running setup.py (path:/tmp/pip-4xthPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:52:53 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)) 05:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:52:53 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)) 05:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:52:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:52:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:52:53 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 05:52:53 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 05:52:53 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 05:52:53 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 05:52:53 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 05:52:53 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 05:52:55 INFO - Downloading blessings-1.6.tar.gz 05:52:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:52:55 INFO - Installing collected packages: blessings 05:52:55 INFO - Running setup.py install for blessings 05:52:55 INFO - Successfully installed blessings 05:52:55 INFO - Cleaning up... 05:52:55 INFO - Return code: 0 05:52:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:52:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:52:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:52:56 INFO - Reading from file tmpfile_stdout 05:52:56 INFO - Current package versions: 05:52:56 INFO - argparse == 1.2.1 05:52:56 INFO - blessings == 1.6 05:52:56 INFO - blobuploader == 1.2.4 05:52:56 INFO - docopt == 0.6.1 05:52:56 INFO - functools32 == 3.2.3-2 05:52:56 INFO - jsonschema == 2.5.1 05:52:56 INFO - manifestparser == 1.1 05:52:56 INFO - mozInstall == 1.12 05:52:56 INFO - mozcrash == 0.17 05:52:56 INFO - mozdebug == 0.1 05:52:56 INFO - mozdevice == 0.48 05:52:56 INFO - mozfile == 1.2 05:52:56 INFO - mozhttpd == 0.7 05:52:56 INFO - mozinfo == 0.9 05:52:56 INFO - mozleak == 0.1 05:52:56 INFO - mozlog == 3.4 05:52:56 INFO - moznetwork == 0.27 05:52:56 INFO - mozprocess == 0.23 05:52:56 INFO - mozprofile == 0.28 05:52:56 INFO - mozrunner == 6.12 05:52:56 INFO - mozscreenshot == 0.1 05:52:56 INFO - mozsystemmonitor == 0.3 05:52:56 INFO - moztest == 0.7 05:52:56 INFO - mozversion == 1.4 05:52:56 INFO - psutil == 3.1.1 05:52:56 INFO - requests == 1.2.3 05:52:56 INFO - wsgiref == 0.1.2 05:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:56 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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52:56 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 05:52:56 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 05:52:56 INFO - Using env: {'DISPLAY': ':0', 05:52:56 INFO - 'HOME': '/home/cltbld', 05:52:56 INFO - 'LANG': 'en_US.UTF-8', 05:52:56 INFO - 'LANGUAGE': 'en_US:en', 05:52:56 INFO - 'LOGNAME': 'cltbld', 05:52:56 INFO - 'MAIL': '/var/mail/cltbld', 05:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:56 INFO - 'MOZ_NO_REMOTE': '1', 05:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:56 INFO - 'NO_EM_RESTART': '1', 05:52:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:56 INFO - 'PWD': '/builds/slave/test', 05:52:56 INFO - 'SHELL': '/bin/bash', 05:52:56 INFO - 'SHLVL': '1', 05:52:56 INFO - 'TERM': 'linux', 05:52:56 INFO - 'TMOUT': '86400', 05:52:56 INFO - 'USER': 'cltbld', 05:52:56 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:56 INFO - '_': '/tools/buildbot/bin/python'} 05:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 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)) 05:52:56 INFO - Cleaning up... 05:52:56 INFO - Return code: 0 05:52:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:56 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 0x7f90ee5adf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10678a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11337b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, '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': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', '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 05:52:56 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 05:52:56 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 05:52:56 INFO - Using env: {'DISPLAY': ':0', 05:52:56 INFO - 'HOME': '/home/cltbld', 05:52:56 INFO - 'LANG': 'en_US.UTF-8', 05:52:56 INFO - 'LANGUAGE': 'en_US:en', 05:52:56 INFO - 'LOGNAME': 'cltbld', 05:52:56 INFO - 'MAIL': '/var/mail/cltbld', 05:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:56 INFO - 'MOZ_NO_REMOTE': '1', 05:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:56 INFO - 'NO_EM_RESTART': '1', 05:52:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:52:56 INFO - 'PWD': '/builds/slave/test', 05:52:56 INFO - 'SHELL': '/bin/bash', 05:52:56 INFO - 'SHLVL': '1', 05:52:56 INFO - 'TERM': 'linux', 05:52:56 INFO - 'TMOUT': '86400', 05:52:56 INFO - 'USER': 'cltbld', 05:52:56 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:56 INFO - '_': '/tools/buildbot/bin/python'} 05:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:52:56 INFO - Cleaning up... 05:52:56 INFO - Return code: 0 05:52:56 INFO - Running post-action listener: _resource_record_post_action 05:52:56 INFO - Running post-action listener: _start_resource_monitoring 05:52:56 INFO - Starting resource monitoring. 05:52:56 INFO - [mozharness: 2016-09-21 12:52:56.612077Z] Finished create-virtualenv step (success) 05:52:56 INFO - [mozharness: 2016-09-21 12:52:56.612585Z] Running install step. 05:52:56 INFO - Running pre-action listener: _resource_record_pre_action 05:52:56 INFO - Running main action method: install 05:52:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:52:56 INFO - Reading from file tmpfile_stdout 05:52:56 INFO - Detecting whether we're running mozinstall >=1.0... 05:52:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:52:56 INFO - Reading from file tmpfile_stdout 05:52:56 INFO - Output received: 05:52:56 INFO - Usage: mozinstall [options] installer 05:52:56 INFO - Options: 05:52:56 INFO - -h, --help show this help message and exit 05:52:56 INFO - -d DEST, --destination=DEST 05:52:56 INFO - Directory to install application into. [default: 05:52:56 INFO - "/builds/slave/test"] 05:52:56 INFO - --app=APP Application being installed. [default: firefox] 05:52:56 INFO - mkdir: /builds/slave/test/build/application 05:52:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:53:13 INFO - Reading from file tmpfile_stdout 05:53:13 INFO - Output received: 05:53:13 INFO - /builds/slave/test/build/application/firefox/firefox 05:53:13 INFO - Running post-action listener: _resource_record_post_action 05:53:13 INFO - [mozharness: 2016-09-21 12:53:13.876714Z] Finished install step (success) 05:53:13 INFO - [mozharness: 2016-09-21 12:53:13.876961Z] Running run-tests step. 05:53:13 INFO - Running pre-action listener: _resource_record_pre_action 05:53:13 INFO - Running main action method: run_tests 05:53:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:53:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:53:13 INFO - Python 2.7.3 05:53:13 INFO - Return code: 0 05:53:13 INFO - grabbing minidump binary from tooltool 05:53: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')]} 05:53:13 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 0x11c2790>, '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 0x11c3690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:53:13 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 05:53:13 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 05:53:13 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 05:53:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:53:13 INFO - Return code: 0 05:53:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:53:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:53:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:53:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:53:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:53:13 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/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 05:53:13 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/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --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 05:53:13 INFO - Using env: {'DISPLAY': ':0', 05:53:13 INFO - 'HOME': '/home/cltbld', 05:53:13 INFO - 'LANG': 'en_US.UTF-8', 05:53:13 INFO - 'LANGUAGE': 'en_US:en', 05:53:13 INFO - 'LOGNAME': 'cltbld', 05:53:13 INFO - 'MAIL': '/var/mail/cltbld', 05:53:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:53:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:13 INFO - 'MOZ_NO_REMOTE': '1', 05:53:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:53:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:53:13 INFO - 'NO_EM_RESTART': '1', 05:53:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:53:13 INFO - 'PWD': '/builds/slave/test', 05:53:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:53:13 INFO - 'SHELL': '/bin/bash', 05:53:13 INFO - 'SHLVL': '1', 05:53:13 INFO - 'TERM': 'linux', 05:53:13 INFO - 'TMOUT': '86400', 05:53:13 INFO - 'USER': 'cltbld', 05:53:13 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975', 05:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:13 INFO - '_': '/tools/buildbot/bin/python'} 05:53:13 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/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--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 05:53:14 INFO - mozversion application_buildid: 20160921030221 05:53:14 INFO - mozversion application_changeset: e2d2897e4a7449759267bf168e6b37f7b8c3a94b 05:53:14 INFO - mozversion application_display_name: Nightly 05:53:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:53:14 INFO - mozversion application_name: Firefox 05:53:14 INFO - mozversion application_remotingname: firefox 05:53:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:53:14 INFO - mozversion application_vendor: Mozilla 05:53:14 INFO - mozversion application_version: 52.0a1 05:53:14 INFO - mozversion platform_buildid: 20160921030221 05:53:14 INFO - mozversion platform_changeset: e2d2897e4a7449759267bf168e6b37f7b8c3a94b 05:53:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:53:14 INFO - mozversion platform_version: 52.0a1 05:53:14 INFO - using testdate: 1474462394 05:53:14 INFO - actual date: 1474462394 05:53:14 INFO - starting webserver on 'localhost:43685' 05:53:14 INFO - SUITE-START | Running 2 tests 05:53:14 INFO - TEST-START | dromaeo_css 05:53:14 INFO - operating with platform_type : linux_ 05:53:14 INFO - Initialising browser for dromaeo_css test... 05:53:14 INFO - TEST-INFO | started process 23433 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYsXCFu/profile http://localhost:43685/getInfo.html) 05:53:18 INFO - PROCESS | 23433 | __metrics Screen width/height:1600/1200 05:53:18 INFO - PROCESS | 23433 | colorDepth:24 05:53:18 INFO - PROCESS | 23433 | Browser inner width/height: 1024/697 05:53:18 INFO - PROCESS | 23433 | __metrics 05:53:18 INFO - PROCESS | 23433 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:53:18 INFO - PROCESS | 23433 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:53:19 INFO - PROCESS | 23433 | console.error: PushService: 05:53:19 INFO - PROCESS | 23433 | stateChangeProcessEnqueue: Error transitioning state 05:53:19 INFO - PROCESS | 23433 | UnknownError 05:53:19 INFO - TEST-INFO | 23433: exit 0 05:53:19 INFO - Browser initialized. 05:53:19 INFO - Running cycle 1/1 for dromaeo_css test... 05:53:19 INFO - TEST-INFO | started process 23515 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYsXCFu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:53:32 INFO - PROCESS | 23515 | RSS: Main: 172994560 05:53:32 INFO - PROCESS | 23515 | 05:53:35 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.13337 seconds. 05:53:39 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:53:49 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:54:07 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:54:23 INFO - PROCESS | 23515 | 1474462463107 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:54:28 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:54:40 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 05:54:50 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 05:54:52 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 05:55:03 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:55:05 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:55:07 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 05:55:24 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 05:55:35 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:55:48 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 05:56:08 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 05:56:10 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 05:56:21 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:56:32 INFO - PROCESS | 23515 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43685/tests/dromaeo/cssquery-ext.html) 05:56:34 INFO - PROCESS | 23515 | RSS: Main: 278859776 05:56:34 INFO - PROCESS | 23515 | 05:56:38 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:56:43 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 05:56:52 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 05:57:00 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:57:06 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:57:25 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:57:29 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:57:31 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 05:57:37 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 05:57:45 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:57:47 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:57:59 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:58:21 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 05:58:25 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:58:29 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:58:31 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:58:48 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 05:58:55 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 05:58:58 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 05:59:01 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 05:59:18 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 05:59:36 INFO - PROCESS | 23515 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-ext.html (next: http://localhost:43685/tests/dromaeo/cssquery-jquery.html) 05:59:36 INFO - PROCESS | 23515 | RSS: Main: 273465344 05:59:36 INFO - PROCESS | 23515 | 05:59:43 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.06684 seconds. 05:59:51 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 06:00:03 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:00:08 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:00:09 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:00:23 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:00:34 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:00:48 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:00:50 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 06:00:56 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 06:01:04 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:01:10 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 06:01:12 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:01:34 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:01:38 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:01:42 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:01:56 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:02:04 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 06:02:06 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:02:16 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:02:18 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:02:27 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:02:30 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:02:33 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:02:38 INFO - PROCESS | 23515 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43685/tests/dromaeo/cssquery-mootools.html) 06:02:39 INFO - PROCESS | 23515 | RSS: Main: 266944512 06:02:39 INFO - PROCESS | 23515 | 06:02:46 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:02:55 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:03:18 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:03:25 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:03:26 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:03:34 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:03:40 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:03:42 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:03:44 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:04:10 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:04:12 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:04:22 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:04:24 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:04:39 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:04:45 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:04:47 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:04:53 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:04:57 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:04:59 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 06:05:03 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:05:10 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:05:20 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:05:25 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:05:37 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:05:40 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:05:41 INFO - PROCESS | 23515 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43685/tests/dromaeo/cssquery-prototype.html) 06:05:42 INFO - PROCESS | 23515 | RSS: Main: 250597376 06:05:42 INFO - PROCESS | 23515 | 06:05:47 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.10007 seconds. 06:06:00 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:06:03 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:06:05 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:06:08 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:06:09 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 06:06:11 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 06:06:19 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:06:29 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 06:06:33 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:06:35 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:06:39 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:06:57 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 06:06:59 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:07:01 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:07:03 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:07:07 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:07:12 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 06:07:27 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:07:30 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:07:39 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 06:07:56 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:08:08 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:08:12 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:08:20 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:08:33 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 06:08:38 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:08:44 INFO - PROCESS | 23515 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43685/tests/dromaeo/cssquery-yui.html) 06:08:45 INFO - PROCESS | 23515 | RSS: Main: 218157056 06:08:45 INFO - PROCESS | 23515 | 06:08:48 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 06:08:49 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 06:08:52 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:09:08 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 06:09:16 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:09:30 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:09:46 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 06:09:50 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:10:17 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:10:21 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:10:25 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 06:10:27 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 06:10:29 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 06:10:47 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:10:49 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:10:55 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 06:11:13 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 06:11:40 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 06:11:46 INFO - PROCESS | 23515 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 06:11:48 INFO - PROCESS | 23515 | Cycle 1(1): loaded http://localhost:43685/tests/dromaeo/cssquery-yui.html (next: http://localhost:43685/tests/dromaeo/cssquery-dojo.html) 06:11:48 INFO - PROCESS | 23515 | RSS: Main: 310652928 06:11:48 INFO - PROCESS | 23515 | 06:11:48 INFO - PROCESS | 23515 | __start_tp_report 06:11:48 INFO - PROCESS | 23515 | _x_x_mozilla_page_load 06:11:48 INFO - PROCESS | 23515 | _x_x_mozilla_page_load_details 06:11:48 INFO - PROCESS | 23515 | |i|pagename|runs| 06:11:48 INFO - PROCESS | 23515 | |0;dojo.html;2906.34607213377;2987.178525905376;3003.8347890866007;3021.9697803021963;3051.4507388670045;5055.823046193384;5142.305788718522;5171.508706672865;5255.237990491381;5320.920186197211;5280.339957505312;5298.993191293652;5343.519083282504;5343.893122137555;5367.819079802447;4530.686635762108;4783.186858234091;4810.735409552473;4845.806167753286;4869.099216644914;4912.926306105412;4957.801687932479;4958.281049247857;4964.4787297333805;5030.522100400474;4628.722276663378;4661.067786442725;4719.126961512097;4909.3126962225315;4922.310876477296;4741.336212930158;4745.050989802054;4745.3593764842;4745.430548334222;4745.928811067837;4702.153612349775;4722.8110875564935;4725.716457012591;4725.929111063337;4730.124926888537;5098.5411312982005;5145.279660847485;5161.8193363232485;5214.400343960418;5242.947570524284;5563.833085007456;5566.916496252519;5568.387477377485;5616.410276920946;5630.436956304377;5662.886742265132;5665.405132461115;5672.574556908207;5673.943260567383;5675.914861277084;5124.92312615311;5167.328247327823;5288.947110528884;5298.576113910878;5308.0710875596615;5675.772969081273;5690.601657883908;5727.198192253088;5759.049756790083;5843.474087332161;5521.282233309644;5521.641093328921;5526.917096243559;5527.474889885454;5528.44715528452;5389.83830485086;5422.945770542323;5606.411326810708;5607.271054762855;5618.213450116987;5533.253010843565;5573.414791446839;5580.944190558123;5582.49757521825;5647.943520564824;5581.162825576196;5591.217229587861;5595.524380427628;5596.944030559724;5663.235463212496;4174.540800511942;4336.566343365638;4346.717463364871;4350.71720338177;4399.296112621965;3756.079760458705;3855.0169706724605;3867.961320386816;3887.902802429962;3962.425448309979;5317.2024196370085;5365.865853353619;5377.274068000848;5385.946140538545;5414.458554144554;5643.548516118701;5671.404502527175;5695.288088988877;5716.085426331767;5733.741981610838;5125.051865404912;5170.586353091744;5252.632315737862;5287.365516138088;5307.310049693515;4893.4617192108835;4896.2655601660035;4900.779464924087;4927.260910863399;4952.70283782967;5800.332961709373;5801.970990145022;5804.622699524433;5805.332386775575;5807;5417.620766546304;5462.781488740406;5590.8043218487155;5645.915311270416;5691.7438715257085;5821.417858214145;5822.70886455667;5858.560607954506;5871.2073870027;5885.676287804212;5472.808451704172;5524.254225679482;5527.474889885454;5529.170624406372;5530.419305972976;5394.9460505394445;5469.179623056573;5495.560355171497;5591.217229587943;5612.438756124355;5416.21464887597;5435.347758269033;5441.075017246998;5488.231647569263;5494.395616482104;5443.237946687546;5443.265159203457;5447.564194864481;5448.618596698351;5450.427705090909;5264.341957255343;5306.416294207711;5376.973115134399;5379.031774280667;5432.185172224198;5505.697186654773;5544.473275038865;5554.527865131509;5581.274434323512;5639.407862174577;5416.214648875812;5452.291202143696;5456.945430545803;5461.098918678533;5464.617476776746 06:11:48 INFO - PROCESS | 23515 | |1;ext.html;556.3101753825256;594.7710131599334;618.5762752514527;631.9936800631995;634.6509419819098;31605.84197079014;34157.658423415756;34256.82871585645;34265.48601770972;42659;40266.19467610646;44594.777026114825;44602;44741.55258447414;44781.55218447823;8815.515146666914;8959.238464730506;8967.596458159365;8970.730878073635;8971.551422428885;8866.069062748415;8929.330300227477;8955.507447090407;8955.552222388855;8958.104189581023;8936.776580585505;8969.551522423886;8993.325500587449;9038.502882341469;9044.276457883354;8863;8891.510966896785;8900.465972041698;8917.063708310632;8939.240164585954;8849;8920.643174273022;8920.8661870072;8921.509316987564;8931.866022009675;42389.57610423887;42445;42665.57334426647;42699.7865010676;43096.56903430957;3411.5308547259806;3416.197193659488;3616.2405894762132;3788.772673639563;4065.5934406559404;30763.956856334986;31685.52471712926;31760.523592146135;31763.364732705446;31819.522707159413;4259.31850903857;4348.152110338485;4350.260455722503;4497.662675299365;4537.296719008559;361585;363139;369968;370587;371591;56581.43418565844;56859;56894.7155264221;56899;56900.430995689516;59645.40354596485;59681.403185968455;59691.70154149202;59694;59864;16309.347626094823;16489.422870199487;16493.257803398876;16494.75257871133;16517;13475.932620336836;13607.727845443036;13617.523386681418;13624.523141689993;13626;12654.430550625244;16147.677046459004;16180.514584562481;16192.838071619133;16271.755923661154;5226.529612334908;5763.250777398911;5954.76180952757;5989.880202395928;6017.69911504423;945.5319616789733;964.3538828984597;1026.712520494263;1035.5443604813859;1070.4273213830718;30645.233869153002;31434.21414464657;31594.684053159635;31618.525722114187;32259;1109.1958330210693;1127.9943600281947;1132.4507613807298;1203.2900588652562;1221.4137214137263;16242;16808.831911680973;17012.404565840137;17092.06564437957;17235.91382043082;512764;516257;518830;518873;518945;57523.71238143783;57765;57791.13313300304;57814.421855781744;57838.97563585254;1252.273681264864;1290.0389210038581;1297.9935100324437;1338.7188690374935;1346.9326533673277;29412.852935735184;32320;32694.83652581722;32815.50776738303;33265.168370790925;43648.34527482027;44016;44065.77967110144;44092.55907440885;44096;389153;389797;390167;390628;390640;39374.80312598419;40076;40840.79579602083;41204.32171908816;41204.58795412008;46614.76692616515;47309.52690473051;47561.52438475571;47649.52350476451;48093.75953120212;39725;40667;40790.79604601958;40900.79549602233;42695.5730442704;39785;40171.39742903801;40216.79891600523;41593.42132705721;41741.37387939123;39672.76907229782;40818.79590602028;41317;41338.79330603448;41529.79235103805;31013.68986310108;34754.304913901076;34826.651733483355;35695.28609427745;35893.641063590076;26144.73855261423;27451.725482744918;27516.58725119085;27849.443011139258;28000.71999279981 06:11:48 INFO - PROCESS | 23515 | |2;jquery.html;372.18491503607083;388.26423926658964;392.1314288850196;396.2016536678593;422.5584264443656;13236.022261393124;15369.846301536982;16338.999999999985;19343.516412089706;19790.505237369074;18946.336878209266;19018.61962760744;19237.615247695037;19565.119569619364;19599;5621.634593751413;5685.516731077863;5874.765009399619;5878.764849406019;5878.794242201524;5660.235868157808;5693.373728889819;5703.429657034284;5719.25649665545;5727.885442291152;5612.158176273571;5705.201271821928;5721.141828725683;5726.799562015331;5727.455891690282;5775.364709881909;5944.791932282349;5945.16767652529;5949.137375080611;5953.70231488426;5612.719364031782;5746.626469279525;5755.4244575542525;5772.624779389368;5791.189233507313;14001.369938352798;14054.859451405457;14149.504767333094;14228.28858557068;14248.786268205882;2332.918347857834;2355.4817940053163;2610.2169349195356;2821.083147976916;2858.5426331786816;9512.571934262975;9558.90441095587;9645.71062868115;10092.697219083439;10166.9491652542;1810.1130446081456;1843.1521500109966;1880.511067122558;1887.7829049659329;1954.3843689237845;78922;83284.58357708172;84838.84997825346;84888.57555712244;85529;10679.412632305142;10905;10955.83566246507;11027.83458248127;11418.657440276638;14886.404543818126;15452.613684657748;15459.458918937784;15461;15468.690626187637;7934.761957141295;8316.251537361546;8342.791430214293;8423.873641895376;8523.232909038092;7807.960960195162;7904.1700621435075;8028.197180282042;8138.796530086677;8210.466319689202;4718.363020992191;4777.617790576815;4806.2310030395665;4940.407151141814;4965.577925876341;1908.4656296237076;1909.799471055547;1966.3314473079222;1972.3688419705843;1979.7228388025687;805.923437273458;875.2123089219754;911.4303560274828;918.9632414703337;970.7912798748303;17607.735883961752;18733.531661708566;18785.7182142268;19429.805701942798;19596.412107636792;965.5751469353462;971.3734641156556;972.1056627902346;1016.0702956794598;1036.2073014144187;10277.537510812032;10842.403667798348;10916.235863489479;10937.617183398543;10940;468332;468834;474144;479131;480264;99467;101409.49295253477;101548.49225753972;103719;103808;1014.660088870222;1055.1400608504046;1058.0953284941418;1095.6498311122598;1099.2525082943675;17192.22634981404;17542.12289385551;18030.72953905666;18039.933211354364;18404.723929140804;41887.790561047;42544.78727606342;43753.34369984389;43834.56165438432;44001.33997989968;55375;60939.69530152321;62130;62648.68675656593;64210.67894660497;8072;8234.917650823416;8590.82818343642;8626.82746345057;8634.30925525969;12466.5013399463;12796.680082998;12883.677908052374;12996.02529810287;13106.803397949228;10938.23432359759;11714.824277636013;11748.17762756633;11969.10231732641;12271.07966902468;10899.945500272448;11235.269707468988;11476.024537914036;11621.535138594361;11820.290782553075;11419.828702569303;11678.532858685558;11715.824262635897;12167.148299618942;12272.877271227173;7444.627768611656;7767.805804854924;8452;8583.484990900566;8904.86642700373;11020.834687479533;11378.829317560409;11442.771144576895;11533.653990380302;11985.460654270753 06:11:48 INFO - PROCESS | 23515 | |3;mootools.html;1320.7292505036467;1403.2773121842251;1444.7471692453819;1689.9408520701777;1759.868009899258;7609.505382150171;7824.413169012319;8652.567371631418;9324.067593240672;9332.393394429357;8603.311735061196;8885.91114088859;8893.421927574704;9114.726558203249;9117.0427105154;2434.1967150840223;2454.104251948044;2458.50829834033;2461.6061430171176;2470;2102.2642075273607;2156.6872803443493;2388.021360786847;2475.938101547467;2477.950440991179;2308.272894038381;2385.9284221473295;2390.3904504351453;2396.9985062472856;2408.1932552594903;2415.0581273303424;2429.2226487523913;2458.4714286428502;2484.043643197364;2487.1046423287603;2454.4109413740575;2460.8400453970435;2466.938326541833;2469.1851688942606;2477.2939712182124;8999.82000359989;9180.403273787182;9200.217981471584;9201.677941272023;9203.585838637277;5444.6188766786345;5609.102543593006;5614.382417934024;5616.5787565932715;5622.550195984311;3368.3936891359544;3555.3600351936643;3586.6234045425376;3603.0992251937014;3624.2207925295925;1177.5113327968882;1236.1779416687891;1237.2267332916927;2160.8055275025167;2200.1529411176653;12693.11148219625;12819.230846149261;12837.87162128376;12840.036997225152;12843.422046007952;4445.244308467568;4627.190241707688;4755.476897541268;4786.210275304606;4833.154198015333;9963.601455941825;9967.850482242773;9971.601135954481;9973.301868869255;9975.351602145982;5385.8114965976;5386.272853164851;5388.784448622011;5391.16436952273;5398.1902714593125;4991.554138472123;5020.59835213182;5031.22016087507;5044.0920634286185;5067.594592432597;3654.8720794772057;3718.9628103719156;3729.4778730977687;3754.3992961126637;3823.8279277432584;996.1532697207316;1026.3072426112494;1112.8942750438696;1120.0815331428314;1146.3694967767694;808.9919100809034;936.442816524167;961.4519723757392;969.413504829582;1009.8384258518602;5679.4320567943705;5919.408059194132;6087.178230938766;6283.214598175228;6358.491320694333;914.9588268527932;923.8429466990493;947.3415975896686;951.3911096897991;989.8960609135937;3977.065389633434;4167.166446763415;4167.291560434673;4237.809298581571;4306.923269182705;11415.714607134722;11796.823047654292;11808.70478238051;11811.232269902428;11812.822807657893;11571.884281157249;11645;11691.532338706527;11692.532298707956;11784;8350.248477637042;8486.575671216415;8595.140485951355;8605.655773769104;8621.482711037357;6099.512039036777;6123.203983482119;6129.570930034854;6211.751529938751;6227.626342419469;10333.689989300334;10426.322289051188;10461.215408844519;10498.265121441425;10531.57873685044;10489;10541.578336866747;10737.570497180026;10751.946240268748;10903;7585.886211706939;7790.454668173174;7791.961040194763;7795;7795.454318197899;8784.385093043426;8787.253083487974;8862.42394244372;8870.866936996079;8919.910800892168;6370.713317900798;7896.9605151976175;7964.681412743425;7975;8020.598970051357;7674.501157424749;7827.960860195891;7869.763907082796;7895;7953.6818527260575;6762.797116086525;6795;6844.2129155147795;6851.794446166623;6851.794446166623;4830.154722923404;5111.131107711623;5159.742012899265;5245.763940622604;5250.081235783646;5183.092958732132;5188.533032027136;5233.267342572119;5265.2365407015;5429.077056900415 06:11:48 INFO - PROCESS | 23515 | |4;prototype.html;322.7063372331179;329.2985939947911;337.64041296019747;338.30647173294756;339.9048266485385;5099.923501147486;5235.79056837727;5269.473052694733;5327.893442131155;5339.679619222849;5041.294218809369;5149.485051494848;5149.639525233235;5151.562117220032;5182.870428239286;4110.506739191302;4122.536499542664;4123.9381409278885;4132.780829655244;4184.727992680481;3940.4089386591954;4140.523839758421;4157;4163.542010378856;4172.102997855461;4085.775282359469;4090.650085471802;4103.158852435244;4107.486564179478;4150.232207041707;4092.8772136835646;4096.938545921814;4098.426220329156;4106.630403263721;4154.626083652486;3674.467202255658;3676.8529258829612;3681.300552894968;3684.6868016218386;3689.760165589255;5117.334746482933;5135.794568217267;5185.611079169077;5193.766280517386;5194.870128246786;4657.301404789275;4661.603763680091;4664.603508701731;4669.34629151919;4673.6027437667835;5382.865428364283;5441.483059109379;5467.699276539789;5470.91793623096;5480.314960629921;3882.4176373544196;3927.56796752357;3944.881653550398;3959;3964.920701585981;5669.546436285129;5698.544116470673;5713.999999999958;5722.37053924068;5726.68503232322;4050.1697152083752;4055.756654600674;4059.79701014954;4067.91864162721;4075.7962101894786;3872.5159355080614;4098.118904435561;4116.567760385176;4123.793810309473;4133.772642504691;4671.672982891233;4683.81264749413;4684.648651351163;4685.672002959828;4741.316832524473;5568.359638641527;5579.553635709133;5583.302087239095;5657.660540367591;5661.915071273934;1316.9539066132638;1341.8658134186503;1395.1350162899068;1413.4204975959808;1421.1686163594413;891.741394995444;897.1028971028971;913.3834661603391;927.526961249754;929.1126973740062;4778.845961665284;4788.257820037899;4813.638977076734;4822.54050434274;4853.296272040534;4939.925901111486;4942.505749425029;4973.875653108701;5022.392834292991;5027.296178535008;4512.729236245836;4700.835470758576;4718.4664983880375;4719.95280047202;4722.740249286322;2599.740025997385;2600.388908606476;2601.5187190369843;2615.2415799418336;2621.239840446249;5045.823396181116;5052.924206136838;5109.233614957711;5135.486451354909;5136.743162841918;4776.423780152541;5028.723420211923;5059.519345662156;5060.7216603086445;5122.359705036871;5034.572061374751;5103.8468845934685;5122.513361230752;5124.538791508782;5165;4908.950910490849;4930.362256193063;4940.901181976412;4954.702717836942;5039.949600503948;4599.241125214436;4924.655274130777;4927.285543596086;4931.48219436954;4961.479044700254;5064.151245053712;5100.63358183597;5105.234214867651;5120.590352771844;5144.30551875492;3673.357162496499;3682.558093028854;3682.8526858925343;3736.140687641803;3747.494088298045;4888.364512613338;5025.899482010413;5028.522290382408;5070.881407934167;5461.1808228766;5489.423610520837;5511.311086114236;5570.58220633446;5578.163275508706;5595.804146854841;5564.360098588714;5569;5598.068666310117;5615.3823079462045;5656.264685590847;5257.658252213594;5533.3083364579425;5533.667979921218;5536.4186760389575;5562;5628.015097358028;5642.23829782974;5646.209530665628;5646.491815736604;5654.802081927113;5531.612787104864;5534.39121696605;5547.278853748987;5601.5238704710555;5615.943840561542 06:11:48 INFO - PROCESS | 23515 | |5;yui.html;685.5817951049862;728.2389902551832;778.7741554949065;819.4468733604815;827.4184544222028;14113;15120.924395378035;15121.546353609408;15148.545543633694;15149.697006059885;13496.595102146926;14185.574432767007;14340.42638294467;14396;14426.927865360685;784.7724159993595;800.1158719614829;805.9556724380186;806.7257132574953;807.1807115777491;658.8221180281321;670.0752960913957;782.3897360059163;798.7763426240662;805.9435839491209;846.4328899637258;847.3306088190322;847.7456762971115;847.7753395350206;848.6350869126273;838.0362583029504;838.4172999765166;838.6687258532845;838.7190291252438;839.0057781528877;505.5475349562158;514.5934711577886;595.2262059322907;607.1318015238252;619.6529943231805;14204.715905681931;14223.786643200361;14235.644108897257;14264.35810388535;14268.357923893449;12848.293343866084;13520.729585408237;13625.386857591433;13656.93171534136;13823.308834558231;11593.304401735923;11871.821922671166;11883.940580297043;12157.696057598543;12268;4396.912061758779;4419.712718673276;4546.477155127137;4647.535246475325;4689.507601701806;79945;81072.59463702702;81208.9999999994;82603;85899.5705021471;10806.675799726021;10959.50682219302;11034.779304414027;11091.889081109086;11235.66293011211;20029.39911802619;20052;20070.698939515918;20083.598328033357;20103.597928041356;8221.136780638066;8289.336853051742;8369.079401265855;8390.077091519928;8488.83022339562;4602.332661764025;4808.206645903457;4840.878978025577;4888.120138375127;5009.073321435546;3367.983160084184;3485.6514348564942;3486.3201675673;3488.860445582199;3500.0024992876906;588.6791698524314;594.910763385487;595.5771402304413;597.6025942748024;602.3524710935761;587.3011116771029;620.3083561828529;632.7342516143231;655.3773914780977;691.3466773898718;9132.863007054899;9179.816403672023;9715.757106072404;9800.313978021613;9918.80162396748;606.6693651959604;621.6301300726061;631.2141383976978;636.4017823246134;657.8552718401944;4238.830446782094;4326.58897404746;4373.190959672471;4456.821727130879;4616.146012987608;102995.4850225744;125954;128422;130755;133131.3343433296;109359;112789.4360528192;114350.42824785823;114498.42750786192;117920.41039794746;96777.51611241899;97472;98137.50931245298;101440;103002;8040.919590804251;8287.12985136552;8385.874211886949;8450.154984501502;8452.619632116692;57101;57350.7132464335;57675.711621441624;57968.71015644895;62161;76070.61964690141;78919;82831.71682831668;83716.58141709253;84595.57702211695;11501.769964600828;11734.471948762497;11850.407479626158;12556.969591313446;12711.216396539738;11860.76278474443;11912.761744764883;12117.69705757328;12259.141860069709;12855.935720321713;12255.938720306342;12288.207599036205;12421.19262247951;12608.810867837174;12715.745685086062;12653.936730316289;12727;12734.172278801849;12735.936320318338;12764.744705106032;10743.715031518599;11274.887251127711;11289.20975531705;11341;11438.94280528592;9268;9445.905540944503;9703.272254581076;10265.538050787882;10356.689299321033;8795.91204087951;10213.693589192337;10288.148133336072;10386.844197336895;10391.220658450799 06:11:48 INFO - PROCESS | 23515 | __end_tp_report 06:11:48 INFO - PROCESS | 23515 | __start_cc_report 06:11:48 INFO - PROCESS | 23515 | _x_x_mozilla_cycle_collect,1060 06:11:48 INFO - PROCESS | 23515 | __end_cc_report 06:11:48 INFO - PROCESS | 23515 | __startTimestamp1474463508404__endTimestamp 06:11:48 INFO - PROCESS | 23515 | 06:11:48 INFO - PROCESS | 23515 | ------- Summary: start ------- 06:11:48 INFO - PROCESS | 23515 | Number of tests: 6 06:11:48 INFO - PROCESS | 23515 | 06:11:48 INFO - PROCESS | 23515 | [#0] dojo.html Cycles:165 Average:5213.31 Median:5416.21 stddev:589.89 (10.9%) stddev-sans-first:563.25 06:11:48 INFO - PROCESS | 23515 | Values: 2906.3 2987.2 3003.8 3022.0 3051.5 5055.8 5142.3 5171.5 5255.2 5320.9 5280.3 5299.0 5343.5 5343.9 5367.8 4530.7 4783.2 4810.7 4845.8 4869.1 4912.9 4957.8 4958.3 4964.5 5030.5 4628.7 4661.1 4719.1 4909.3 4922.3 4741.3 4745.1 4745.4 4745.4 4745.9 4702.2 4722.8 4725.7 4725.9 4730.1 5098.5 5145.3 5161.8 5214.4 5242.9 5563.8 5566.9 5568.4 5616.4 5630.4 5662.9 5665.4 5672.6 5673.9 5675.9 5124.9 5167.3 5288.9 5298.6 5308.1 5675.8 5690.6 5727.2 5759.0 5843.5 5521.3 5521.6 5526.9 5527.5 5528.4 5389.8 5422.9 5606.4 5607.3 5618.2 5533.3 5573.4 5580.9 5582.5 5647.9 5581.2 5591.2 5595.5 5596.9 5663.2 4174.5 4336.6 4346.7 4350.7 4399.3 3756.1 3855.0 3868.0 3887.9 3962.4 5317.2 5365.9 5377.3 5385.9 5414.5 5643.5 5671.4 5695.3 5716.1 5733.7 5125.1 5170.6 5252.6 5287.4 5307.3 4893.5 4896.3 4900.8 4927.3 4952.7 5800.3 5802.0 5804.6 5805.3 5807.0 5417.6 5462.8 5590.8 5645.9 5691.7 5821.4 5822.7 5858.6 5871.2 5885.7 5472.8 5524.3 5527.5 5529.2 5530.4 5394.9 5469.2 5495.6 5591.2 5612.4 5416.2 5435.3 5441.1 5488.2 5494.4 5443.2 5443.3 5447.6 5448.6 5450.4 5264.3 5306.4 5377.0 5379.0 5432.2 5505.7 5544.5 5554.5 5581.3 5639.4 5416.2 5452.3 5456.9 5461.1 5464.6 06:11:48 INFO - PROCESS | 23515 | 06:11:48 INFO - PROCESS | 23515 | [#1] ext.html Cycles:180 Average:58536.28 Median:41204.45 stddev:113798.58 (276.2%) stddev-sans-first:114034.56 06:11:48 INFO - PROCESS | 23515 | Values: 556.3 594.8 618.6 632.0 634.7 31605.8 34157.7 34256.8 34265.5 42659.0 40266.2 44594.8 44602.0 44741.6 44781.6 8815.5 8959.2 8967.6 8970.7 8971.6 8866.1 8929.3 8955.5 8955.6 8958.1 8936.8 8969.6 8993.3 9038.5 9044.3 8863.0 8891.5 8900.5 8917.1 8939.2 8849.0 8920.6 8920.9 8921.5 8931.9 42389.6 42445.0 42665.6 42699.8 43096.6 3411.5 3416.2 3616.2 3788.8 4065.6 30764.0 31685.5 31760.5 31763.4 31819.5 4259.3 4348.2 4350.3 4497.7 4537.3 361585.0 363139.0 369968.0 370587.0 371591.0 56581.4 56859.0 56894.7 56899.0 56900.4 59645.4 59681.4 59691.7 59694.0 59864.0 16309.3 16489.4 16493.3 16494.8 16517.0 13475.9 13607.7 13617.5 13624.5 13626.0 12654.4 16147.7 16180.5 16192.8 16271.8 5226.5 5763.3 5954.8 5989.9 6017.7 945.5 964.4 1026.7 1035.5 1070.4 30645.2 31434.2 31594.7 31618.5 32259.0 1109.2 1128.0 1132.5 1203.3 1221.4 16242.0 16808.8 17012.4 17092.1 17235.9 512764.0 516257.0 518830.0 518873.0 518945.0 57523.7 57765.0 57791.1 57814.4 57839.0 1252.3 1290.0 1298.0 1338.7 1346.9 29412.9 32320.0 32694.8 32815.5 33265.2 43648.3 44016.0 44065.8 44092.6 44096.0 389153.0 389797.0 390167.0 390628.0 390640.0 39374.8 40076.0 40840.8 41204.3 41204.6 46614.8 47309.5 47561.5 47649.5 48093.8 39725.0 40667.0 40790.8 40900.8 42695.6 39785.0 40171.4 40216.8 41593.4 41741.4 39672.8 40818.8 41317.0 41338.8 41529.8 31013.7 34754.3 34826.7 35695.3 35893.6 26144.7 27451.7 27516.6 27849.4 28000.7 06:11:48 INFO - PROCESS | 23515 | 06:11:48 INFO - PROCESS | 23515 | [#2] jquery.html Cycles:180 Average:28707.03 Median:2715.65 stddev:78648.03 (2896.1%) stddev-sans-first:78839.88 06:11:48 INFO - PROCESS | 23515 | Values: 372.2 388.3 392.1 396.2 422.6 13236.0 15369.8 16339.0 19343.5 19790.5 18946.3 19018.6 19237.6 19565.1 19599.0 5621.6 5685.5 5874.8 5878.8 5878.8 5660.2 5693.4 5703.4 5719.3 5727.9 5612.2 5705.2 5721.1 5726.8 5727.5 5775.4 5944.8 5945.2 5949.1 5953.7 5612.7 5746.6 5755.4 5772.6 5791.2 14001.4 14054.9 14149.5 14228.3 14248.8 2332.9 2355.5 2610.2 2821.1 2858.5 9512.6 9558.9 9645.7 10092.7 10166.9 1810.1 1843.2 1880.5 1887.8 1954.4 78922.0 83284.6 84838.8 84888.6 85529.0 10679.4 10905.0 10955.8 11027.8 11418.7 14886.4 15452.6 15459.5 15461.0 15468.7 7934.8 8316.3 8342.8 8423.9 8523.2 7808.0 7904.2 8028.2 8138.8 8210.5 4718.4 4777.6 4806.2 4940.4 4965.6 1908.5 1909.8 1966.3 1972.4 1979.7 805.9 875.2 911.4 919.0 970.8 17607.7 18733.5 18785.7 19429.8 19596.4 965.6 971.4 972.1 1016.1 1036.2 10277.5 10842.4 10916.2 10937.6 10940.0 468332.0 468834.0 474144.0 479131.0 480264.0 99467.0 101409.5 101548.5 103719.0 103808.0 1014.7 1055.1 1058.1 1095.6 1099.3 17192.2 17542.1 18030.7 18039.9 18404.7 41887.8 42544.8 43753.3 43834.6 44001.3 55375.0 60939.7 62130.0 62648.7 64210.7 8072.0 8234.9 8590.8 8626.8 8634.3 12466.5 12796.7 12883.7 12996.0 13106.8 10938.2 11714.8 11748.2 11969.1 12271.1 10899.9 11235.3 11476.0 11621.5 11820.3 11419.8 11678.5 11715.8 12167.1 12272.9 7444.6 7767.8 8452.0 8583.5 8904.9 11020.8 11378.8 11442.8 11533.7 11985.5 06:11:48 INFO - PROCESS | 23515 | 06:11:48 INFO - PROCESS | 23515 | [#3] mootools.html Cycles:180 Average:5952.77 Median:5089.36 stddev:3416.02 (67.1%) stddev-sans-first:3407.87 06:11:48 INFO - PROCESS | 23515 | Values: 1320.7 1403.3 1444.7 1689.9 1759.9 7609.5 7824.4 8652.6 9324.1 9332.4 8603.3 8885.9 8893.4 9114.7 9117.0 2434.2 2454.1 2458.5 2461.6 2470.0 2102.3 2156.7 2388.0 2475.9 2478.0 2308.3 2385.9 2390.4 2397.0 2408.2 2415.1 2429.2 2458.5 2484.0 2487.1 2454.4 2460.8 2466.9 2469.2 2477.3 8999.8 9180.4 9200.2 9201.7 9203.6 5444.6 5609.1 5614.4 5616.6 5622.6 3368.4 3555.4 3586.6 3603.1 3624.2 1177.5 1236.2 1237.2 2160.8 2200.2 12693.1 12819.2 12837.9 12840.0 12843.4 4445.2 4627.2 4755.5 4786.2 4833.2 9963.6 9967.9 9971.6 9973.3 9975.4 5385.8 5386.3 5388.8 5391.2 5398.2 4991.6 5020.6 5031.2 5044.1 5067.6 3654.9 3719.0 3729.5 3754.4 3823.8 996.2 1026.3 1112.9 1120.1 1146.4 809.0 936.4 961.5 969.4 1009.8 5679.4 5919.4 6087.2 6283.2 6358.5 915.0 923.8 947.3 951.4 989.9 3977.1 4167.2 4167.3 4237.8 4306.9 11415.7 11796.8 11808.7 11811.2 11812.8 11571.9 11645.0 11691.5 11692.5 11784.0 8350.2 8486.6 8595.1 8605.7 8621.5 6099.5 6123.2 6129.6 6211.8 6227.6 10333.7 10426.3 10461.2 10498.3 10531.6 10489.0 10541.6 10737.6 10751.9 10903.0 7585.9 7790.5 7792.0 7795.0 7795.5 8784.4 8787.3 8862.4 8870.9 8919.9 6370.7 7897.0 7964.7 7975.0 8020.6 7674.5 7828.0 7869.8 7895.0 7953.7 6762.8 6795.0 6844.2 6851.8 6851.8 4830.2 5111.1 5159.7 5245.8 5250.1 5183.1 5188.5 5233.3 5265.2 5429.1 06:11:48 INFO - PROCESS | 23515 | 06:11:48 INFO - PROCESS | 23515 | [#4] prototype.html Cycles:180 Average:4455.05 Median:4948.60 stddev:1288.36 (26.0%) stddev-sans-first:1254.09 06:11:48 INFO - PROCESS | 23515 | Values: 322.7 329.3 337.6 338.3 339.9 5099.9 5235.8 5269.5 5327.9 5339.7 5041.3 5149.5 5149.6 5151.6 5182.9 4110.5 4122.5 4123.9 4132.8 4184.7 3940.4 4140.5 4157.0 4163.5 4172.1 4085.8 4090.7 4103.2 4107.5 4150.2 4092.9 4096.9 4098.4 4106.6 4154.6 3674.5 3676.9 3681.3 3684.7 3689.8 5117.3 5135.8 5185.6 5193.8 5194.9 4657.3 4661.6 4664.6 4669.3 4673.6 5382.9 5441.5 5467.7 5470.9 5480.3 3882.4 3927.6 3944.9 3959.0 3964.9 5669.5 5698.5 5714.0 5722.4 5726.7 4050.2 4055.8 4059.8 4067.9 4075.8 3872.5 4098.1 4116.6 4123.8 4133.8 4671.7 4683.8 4684.6 4685.7 4741.3 5568.4 5579.6 5583.3 5657.7 5661.9 1317.0 1341.9 1395.1 1413.4 1421.2 891.7 897.1 913.4 927.5 929.1 4778.8 4788.3 4813.6 4822.5 4853.3 4939.9 4942.5 4973.9 5022.4 5027.3 4512.7 4700.8 4718.5 4720.0 4722.7 2599.7 2600.4 2601.5 2615.2 2621.2 5045.8 5052.9 5109.2 5135.5 5136.7 4776.4 5028.7 5059.5 5060.7 5122.4 5034.6 5103.8 5122.5 5124.5 5165.0 4909.0 4930.4 4940.9 4954.7 5039.9 4599.2 4924.7 4927.3 4931.5 4961.5 5064.2 5100.6 5105.2 5120.6 5144.3 3673.4 3682.6 3682.9 3736.1 3747.5 4888.4 5025.9 5028.5 5070.9 5461.2 5489.4 5511.3 5570.6 5578.2 5595.8 5564.4 5569.0 5598.1 5615.4 5656.3 5257.7 5533.3 5533.7 5536.4 5562.0 5628.0 5642.2 5646.2 5646.5 5654.8 5531.6 5534.4 5547.3 5601.5 5615.9 06:11:48 INFO - PROCESS | 23515 | 06:11:48 INFO - PROCESS | 23515 | [#5] yui.html Cycles:180 Average:21930.40 Median:4609.24 stddev:33667.42 (730.4%) stddev-sans-first:33724.08 06:11:48 INFO - PROCESS | 23515 | Values: 685.6 728.2 778.8 819.4 827.4 14113.0 15120.9 15121.5 15148.5 15149.7 13496.6 14185.6 14340.4 14396.0 14426.9 784.8 800.1 806.0 806.7 807.2 658.8 670.1 782.4 798.8 805.9 846.4 847.3 847.7 847.8 848.6 838.0 838.4 838.7 838.7 839.0 505.5 514.6 595.2 607.1 619.7 14204.7 14223.8 14235.6 14264.4 14268.4 12848.3 13520.7 13625.4 13656.9 13823.3 11593.3 11871.8 11883.9 12157.7 12268.0 4396.9 4419.7 4546.5 4647.5 4689.5 79945.0 81072.6 81209.0 82603.0 85899.6 10806.7 10959.5 11034.8 11091.9 11235.7 20029.4 20052.0 20070.7 20083.6 20103.6 8221.1 8289.3 8369.1 8390.1 8488.8 4602.3 4808.2 4840.9 4888.1 5009.1 3368.0 3485.7 3486.3 3488.9 3500.0 588.7 594.9 595.6 597.6 602.4 587.3 620.3 632.7 655.4 691.3 9132.9 9179.8 9715.8 9800.3 9918.8 606.7 621.6 631.2 636.4 657.9 4238.8 4326.6 4373.2 4456.8 4616.1 102995.5 125954.0 128422.0 130755.0 133131.3 109359.0 112789.4 114350.4 114498.4 117920.4 96777.5 97472.0 98137.5 101440.0 103002.0 8040.9 8287.1 8385.9 8450.2 8452.6 57101.0 57350.7 57675.7 57968.7 62161.0 76070.6 78919.0 82831.7 83716.6 84595.6 11501.8 11734.5 11850.4 12557.0 12711.2 11860.8 11912.8 12117.7 12259.1 12855.9 12255.9 12288.2 12421.2 12608.8 12715.7 12653.9 12727.0 12734.2 12735.9 12764.7 10743.7 11274.9 11289.2 11341.0 11438.9 9268.0 9445.9 9703.3 10265.5 10356.7 8795.9 10213.7 10288.1 10386.8 10391.2 06:11:48 INFO - PROCESS | 23515 | -------- Summary: end -------- 06:11:48 INFO - PROCESS | 23515 | 06:11:49 INFO - TEST-INFO | 23515: exit 0 06:11:49 INFO - TEST-OK | dromaeo_css | took 1115167ms 06:11:49 INFO - TEST-START | kraken 06:11:49 INFO - operating with platform_type : linux_ 06:11:49 INFO - Initialising browser for kraken test... 06:11:49 INFO - TEST-INFO | started process 23818 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTR0IaC/profile http://localhost:43685/getInfo.html) 06:11:53 INFO - PROCESS | 23818 | __metrics Screen width/height:1600/1200 06:11:53 INFO - PROCESS | 23818 | colorDepth:24 06:11:53 INFO - PROCESS | 23818 | Browser inner width/height: 1024/697 06:11:53 INFO - PROCESS | 23818 | __metrics 06:11:53 INFO - PROCESS | 23818 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:11:53 INFO - PROCESS | 23818 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:11:54 INFO - PROCESS | 23818 | console.error: PushService: 06:11:54 INFO - PROCESS | 23818 | stateChangeProcessEnqueue: Error transitioning state 06:11:54 INFO - PROCESS | 23818 | UnknownError 06:11:55 INFO - TEST-INFO | 23818: exit 0 06:11:55 INFO - Browser initialized. 06:11:55 INFO - Running cycle 1/1 for kraken test... 06:11:55 INFO - TEST-INFO | started process 23900 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTR0IaC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:12:07 INFO - PROCESS | 23900 | RSS: Main: 173195264 06:12:07 INFO - PROCESS | 23900 | 06:12:09 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43685/tests/kraken/driver.html?testName=audio-beat-detection) 06:12:10 INFO - PROCESS | 23900 | RSS: Main: 232890368 06:12:10 INFO - PROCESS | 23900 | 06:12:13 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43685/tests/kraken/driver.html?testName=audio-dft) 06:12:13 INFO - PROCESS | 23900 | RSS: Main: 216821760 06:12:13 INFO - PROCESS | 23900 | 06:12:17 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43685/tests/kraken/driver.html?testName=audio-fft) 06:12:17 INFO - PROCESS | 23900 | RSS: Main: 298512384 06:12:17 INFO - PROCESS | 23900 | 06:12:19 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43685/tests/kraken/driver.html?testName=audio-oscillator) 06:12:20 INFO - PROCESS | 23900 | RSS: Main: 207749120 06:12:20 INFO - PROCESS | 23900 | 06:12:22 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43685/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:12:23 INFO - PROCESS | 23900 | RSS: Main: 212721664 06:12:23 INFO - PROCESS | 23900 | 06:12:26 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43685/tests/kraken/driver.html?testName=imaging-darkroom) 06:12:26 INFO - PROCESS | 23900 | RSS: Main: 248991744 06:12:26 INFO - PROCESS | 23900 | 06:12:30 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43685/tests/kraken/driver.html?testName=imaging-desaturate) 06:12:30 INFO - PROCESS | 23900 | RSS: Main: 248020992 06:12:30 INFO - PROCESS | 23900 | 06:12:33 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43685/tests/kraken/driver.html?testName=json-parse-financial) 06:12:34 INFO - PROCESS | 23900 | RSS: Main: 247840768 06:12:34 INFO - PROCESS | 23900 | 06:12:35 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43685/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:12:36 INFO - PROCESS | 23900 | RSS: Main: 210214912 06:12:36 INFO - PROCESS | 23900 | 06:12:37 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:12:38 INFO - PROCESS | 23900 | RSS: Main: 217899008 06:12:38 INFO - PROCESS | 23900 | 06:12:40 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:12:41 INFO - PROCESS | 23900 | RSS: Main: 222273536 06:12:41 INFO - PROCESS | 23900 | 06:12:43 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:12:44 INFO - PROCESS | 23900 | RSS: Main: 222932992 06:12:44 INFO - PROCESS | 23900 | 06:12:47 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:12:47 INFO - PROCESS | 23900 | RSS: Main: 221732864 06:12:47 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | Cycle 1(1): loaded http://localhost:43685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43685/tests/kraken/driver.html?testName=ai-astar) 06:12:49 INFO - PROCESS | 23900 | RSS: Main: 272588800 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | __start_tp_report 06:12:49 INFO - PROCESS | 23900 | _x_x_mozilla_page_load 06:12:49 INFO - PROCESS | 23900 | _x_x_mozilla_page_load_details 06:12:49 INFO - PROCESS | 23900 | |i|pagename|runs| 06:12:49 INFO - PROCESS | 23900 | |0;ai-astar;110;114;114;111;112;113;114;112;113;112 06:12:49 INFO - PROCESS | 23900 | |1;audio-beat-detection;143;117;119;165;122;120;123;118;145;118 06:12:49 INFO - PROCESS | 23900 | |2;audio-dft;160;161;162;163;164;162;164;162;160;161 06:12:49 INFO - PROCESS | 23900 | |3;audio-fft;76;76;76;107;77;76;75;76;102;76 06:12:49 INFO - PROCESS | 23900 | |4;audio-oscillator;83;82;106;83;82;82;114;83;83;109 06:12:49 INFO - PROCESS | 23900 | |5;imaging-gaussian-blur;116;117;117;118;117;116;117;117;116;117 06:12:49 INFO - PROCESS | 23900 | |6;imaging-darkroom;147;147;146;147;146;147;146;146;147;146 06:12:49 INFO - PROCESS | 23900 | |7;imaging-desaturate;95;95;96;95;94;95;95;95;95;95 06:12:49 INFO - PROCESS | 23900 | |8;json-parse-financial;49;49;49;49;50;51;51;51;51;52 06:12:49 INFO - PROCESS | 23900 | |9;json-stringify-tinderbox;61;62;61;62;61;62;61;61;61;66 06:12:49 INFO - PROCESS | 23900 | |10;stanford-crypto-aes;79;80;78;79;82;85;83;79;78;79 06:12:49 INFO - PROCESS | 23900 | |11;stanford-crypto-ccm;126;126;124;128;137;122;125;126;160;127 06:12:49 INFO - PROCESS | 23900 | |12;stanford-crypto-pbkdf2;169;165;165;164;165;164;165;171;164;162 06:12:49 INFO - PROCESS | 23900 | |13;stanford-crypto-sha256-iterative;66;65;65;64;63;64;65;66;66;64 06:12:49 INFO - PROCESS | 23900 | __end_tp_report 06:12:49 INFO - PROCESS | 23900 | __start_cc_report 06:12:49 INFO - PROCESS | 23900 | _x_x_mozilla_cycle_collect,1597 06:12:49 INFO - PROCESS | 23900 | __end_cc_report 06:12:49 INFO - PROCESS | 23900 | __startTimestamp1474463569623__endTimestamp 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | ------- Summary: start ------- 06:12:49 INFO - PROCESS | 23900 | Number of tests: 14 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:1.35 (1.2%) stddev-sans-first:1.09 06:12:49 INFO - PROCESS | 23900 | Values: 110.0 114.0 114.0 111.0 112.0 113.0 114.0 112.0 113.0 112.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:122.50 stddev:16.33 (13.3%) stddev-sans-first:16.52 06:12:49 INFO - PROCESS | 23900 | Values: 143.0 117.0 119.0 165.0 122.0 120.0 123.0 118.0 145.0 118.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.00 stddev:1.45 (0.9%) stddev-sans-first:1.36 06:12:49 INFO - PROCESS | 23900 | Values: 160.0 161.0 162.0 163.0 164.0 162.0 164.0 162.0 160.0 161.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.00 stddev:12.08 (15.9%) stddev-sans-first:12.64 06:12:49 INFO - PROCESS | 23900 | Values: 76.0 76.0 76.0 107.0 77.0 76.0 75.0 76.0 102.0 76.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:82.50 stddev:13.23 (16.0%) stddev-sans-first:13.74 06:12:49 INFO - PROCESS | 23900 | Values: 83.0 82.0 106.0 83.0 82.0 82.0 114.0 83.0 83.0 109.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.60 06:12:49 INFO - PROCESS | 23900 | Values: 116.0 117.0 117.0 118.0 117.0 116.0 117.0 117.0 116.0 117.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 06:12:49 INFO - PROCESS | 23900 | Values: 147.0 147.0 146.0 147.0 146.0 147.0 146.0 146.0 147.0 146.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 06:12:49 INFO - PROCESS | 23900 | Values: 95.0 95.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:51.00 stddev:1.14 (2.2%) stddev-sans-first:1.12 06:12:49 INFO - PROCESS | 23900 | Values: 49.0 49.0 49.0 49.0 50.0 51.0 51.0 51.0 51.0 52.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:61.50 stddev:1.55 (2.5%) stddev-sans-first:1.62 06:12:49 INFO - PROCESS | 23900 | Values: 61.0 62.0 61.0 62.0 61.0 62.0 61.0 61.0 61.0 66.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:79.50 stddev:2.35 (3.0%) stddev-sans-first:2.45 06:12:49 INFO - PROCESS | 23900 | Values: 79.0 80.0 78.0 79.0 82.0 85.0 83.0 79.0 78.0 79.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#11] stanford-crypto-ccm Cycles:10 Average:130.10 Median:126.50 stddev:11.23 (8.9%) stddev-sans-first:11.81 06:12:49 INFO - PROCESS | 23900 | Values: 126.0 126.0 124.0 128.0 137.0 122.0 125.0 126.0 160.0 127.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:165.00 stddev:2.63 (1.6%) stddev-sans-first:2.45 06:12:49 INFO - PROCESS | 23900 | Values: 169.0 165.0 165.0 164.0 165.0 164.0 165.0 171.0 164.0 162.0 06:12:49 INFO - PROCESS | 23900 | 06:12:49 INFO - PROCESS | 23900 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.03 (1.6%) stddev-sans-first:1.00 06:12:49 INFO - PROCESS | 23900 | Values: 66.0 65.0 65.0 64.0 63.0 64.0 65.0 66.0 66.0 64.0 06:12:49 INFO - PROCESS | 23900 | -------- Summary: end -------- 06:12:49 INFO - PROCESS | 23900 | 06:12:50 INFO - TEST-INFO | 23900: exit 0 06:12:50 INFO - TEST-OK | kraken | took 61497ms 06:12:50 INFO - SUITE-END | took 1176s 06:12:51 INFO - Completed test suite (00:19:37) 06:12:51 INFO - javascript benchmark 06:12:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2906.34607213377, 2987.178525905376, 3003.8347890866007, 3021.9697803021963, 3051.4507388670045, 5055.823046193384, 5142.305788718522, 5171.508706672865, 5255.237990491381, 5320.920186197211, 5280.339957505312, 5298.993191293652, 5343.519083282504, 5343.893122137555, 5367.819079802447, 4530.686635762108, 4783.186858234091, 4810.735409552473, 4845.806167753286, 4869.099216644914, 4912.926306105412, 4957.801687932479, 4958.281049247857, 4964.4787297333805, 5030.522100400474, 4628.722276663378, 4661.067786442725, 4719.126961512097, 4909.3126962225315, 4922.310876477296, 4741.336212930158, 4745.050989802054, 4745.3593764842, 4745.430548334222, 4745.928811067837, 4702.153612349775, 4722.8110875564935, 4725.716457012591, 4725.929111063337, 4730.124926888537, 5098.5411312982005, 5145.279660847485, 5161.8193363232485, 5214.400343960418, 5242.947570524284, 5563.833085007456, 5566.916496252519, 5568.387477377485, 5616.410276920946, 5630.436956304377, 5662.886742265132, 5665.405132461115, 5672.574556908207, 5673.943260567383, 5675.914861277084, 5124.92312615311, 5167.328247327823, 5288.947110528884, 5298.576113910878, 5308.0710875596615, 5675.772969081273, 5690.601657883908, 5727.198192253088, 5759.049756790083, 5843.474087332161, 5521.282233309644, 5521.641093328921, 5526.917096243559, 5527.474889885454, 5528.44715528452, 5389.83830485086, 5422.945770542323, 5606.411326810708, 5607.271054762855, 5618.213450116987, 5533.253010843565, 5573.414791446839, 5580.944190558123, 5582.49757521825, 5647.943520564824, 5581.162825576196, 5591.217229587861, 5595.524380427628, 5596.944030559724, 5663.235463212496, 4174.540800511942, 4336.566343365638, 4346.717463364871, 4350.71720338177, 4399.296112621965, 3756.079760458705, 3855.0169706724605, 3867.961320386816, 3887.902802429962, 3962.425448309979, 5317.2024196370085, 5365.865853353619, 5377.274068000848, 5385.946140538545, 5414.458554144554, 5643.548516118701, 5671.404502527175, 5695.288088988877, 5716.085426331767, 5733.741981610838, 5125.051865404912, 5170.586353091744, 5252.632315737862, 5287.365516138088, 5307.310049693515, 4893.4617192108835, 4896.2655601660035, 4900.779464924087, 4927.260910863399, 4952.70283782967, 5800.332961709373, 5801.970990145022, 5804.622699524433, 5805.332386775575, 5807.0, 5417.620766546304, 5462.781488740406, 5590.8043218487155, 5645.915311270416, 5691.7438715257085, 5821.417858214145, 5822.70886455667, 5858.560607954506, 5871.2073870027, 5885.676287804212, 5472.808451704172, 5524.254225679482, 5527.474889885454, 5529.170624406372, 5530.419305972976, 5394.9460505394445, 5469.179623056573, 5495.560355171497, 5591.217229587943, 5612.438756124355, 5416.21464887597, 5435.347758269033, 5441.075017246998, 5488.231647569263, 5494.395616482104, 5443.237946687546, 5443.265159203457, 5447.564194864481, 5448.618596698351, 5450.427705090909, 5264.341957255343, 5306.416294207711, 5376.973115134399, 5379.031774280667, 5432.185172224198, 5505.697186654773, 5544.473275038865, 5554.527865131509, 5581.274434323512, 5639.407862174577, 5416.214648875812, 5452.291202143696, 5456.945430545803, 5461.098918678533, 5464.617476776746], "value": 5173.395449807153, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.3101753825256, 594.7710131599334, 618.5762752514527, 631.9936800631995, 634.6509419819098, 31605.84197079014, 34157.658423415756, 34256.82871585645, 34265.48601770972, 42659.0, 40266.19467610646, 44594.777026114825, 44602.0, 44741.55258447414, 44781.55218447823, 8815.515146666914, 8959.238464730506, 8967.596458159365, 8970.730878073635, 8971.551422428885, 8866.069062748415, 8929.330300227477, 8955.507447090407, 8955.552222388855, 8958.104189581023, 8936.776580585505, 8969.551522423886, 8993.325500587449, 9038.502882341469, 9044.276457883354, 8863.0, 8891.510966896785, 8900.465972041698, 8917.063708310632, 8939.240164585954, 8849.0, 8920.643174273022, 8920.8661870072, 8921.509316987564, 8931.866022009675, 42389.57610423887, 42445.0, 42665.57334426647, 42699.7865010676, 43096.56903430957, 3411.5308547259806, 3416.197193659488, 3616.2405894762132, 3788.772673639563, 4065.5934406559404, 30763.956856334986, 31685.52471712926, 31760.523592146135, 31763.364732705446, 31819.522707159413, 4259.31850903857, 4348.152110338485, 4350.260455722503, 4497.662675299365, 4537.296719008559, 361585.0, 363139.0, 369968.0, 370587.0, 371591.0, 56581.43418565844, 56859.0, 56894.7155264221, 56899.0, 56900.430995689516, 59645.40354596485, 59681.403185968455, 59691.70154149202, 59694.0, 59864.0, 16309.347626094823, 16489.422870199487, 16493.257803398876, 16494.75257871133, 16517.0, 13475.932620336836, 13607.727845443036, 13617.523386681418, 13624.523141689993, 13626.0, 12654.430550625244, 16147.677046459004, 16180.514584562481, 16192.838071619133, 16271.755923661154, 5226.529612334908, 5763.250777398911, 5954.76180952757, 5989.880202395928, 6017.69911504423, 945.5319616789733, 964.3538828984597, 1026.712520494263, 1035.5443604813859, 1070.4273213830718, 30645.233869153002, 31434.21414464657, 31594.684053159635, 31618.525722114187, 32259.0, 1109.1958330210693, 1127.9943600281947, 1132.4507613807298, 1203.2900588652562, 1221.4137214137263, 16242.0, 16808.831911680973, 17012.404565840137, 17092.06564437957, 17235.91382043082, 512764.0, 516257.0, 518830.0, 518873.0, 518945.0, 57523.71238143783, 57765.0, 57791.13313300304, 57814.421855781744, 57838.97563585254, 1252.273681264864, 1290.0389210038581, 1297.9935100324437, 1338.7188690374935, 1346.9326533673277, 29412.852935735184, 32320.0, 32694.83652581722, 32815.50776738303, 33265.168370790925, 43648.34527482027, 44016.0, 44065.77967110144, 44092.55907440885, 44096.0, 389153.0, 389797.0, 390167.0, 390628.0, 390640.0, 39374.80312598419, 40076.0, 40840.79579602083, 41204.32171908816, 41204.58795412008, 46614.76692616515, 47309.52690473051, 47561.52438475571, 47649.52350476451, 48093.75953120212, 39725.0, 40667.0, 40790.79604601958, 40900.79549602233, 42695.5730442704, 39785.0, 40171.39742903801, 40216.79891600523, 41593.42132705721, 41741.37387939123, 39672.76907229782, 40818.79590602028, 41317.0, 41338.79330603448, 41529.79235103805, 31013.68986310108, 34754.304913901076, 34826.651733483355, 35695.28609427745, 35893.641063590076, 26144.73855261423, 27451.725482744918, 27516.58725119085, 27849.443011139258, 28000.71999279981], "value": 19804.186418506015, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.18491503607083, 388.26423926658964, 392.1314288850196, 396.2016536678593, 422.5584264443656, 13236.022261393124, 15369.846301536982, 16338.999999999985, 19343.516412089706, 19790.505237369074, 18946.336878209266, 19018.61962760744, 19237.615247695037, 19565.119569619364, 19599.0, 5621.634593751413, 5685.516731077863, 5874.765009399619, 5878.764849406019, 5878.794242201524, 5660.235868157808, 5693.373728889819, 5703.429657034284, 5719.25649665545, 5727.885442291152, 5612.158176273571, 5705.201271821928, 5721.141828725683, 5726.799562015331, 5727.455891690282, 5775.364709881909, 5944.791932282349, 5945.16767652529, 5949.137375080611, 5953.70231488426, 5612.719364031782, 5746.626469279525, 5755.4244575542525, 5772.624779389368, 5791.189233507313, 14001.369938352798, 14054.859451405457, 14149.504767333094, 14228.28858557068, 14248.786268205882, 2332.918347857834, 2355.4817940053163, 2610.2169349195356, 2821.083147976916, 2858.5426331786816, 9512.571934262975, 9558.90441095587, 9645.71062868115, 10092.697219083439, 10166.9491652542, 1810.1130446081456, 1843.1521500109966, 1880.511067122558, 1887.7829049659329, 1954.3843689237845, 78922.0, 83284.58357708172, 84838.84997825346, 84888.57555712244, 85529.0, 10679.412632305142, 10905.0, 10955.83566246507, 11027.83458248127, 11418.657440276638, 14886.404543818126, 15452.613684657748, 15459.458918937784, 15461.0, 15468.690626187637, 7934.761957141295, 8316.251537361546, 8342.791430214293, 8423.873641895376, 8523.232909038092, 7807.960960195162, 7904.1700621435075, 8028.197180282042, 8138.796530086677, 8210.466319689202, 4718.363020992191, 4777.617790576815, 4806.2310030395665, 4940.407151141814, 4965.577925876341, 1908.4656296237076, 1909.799471055547, 1966.3314473079222, 1972.3688419705843, 1979.7228388025687, 805.923437273458, 875.2123089219754, 911.4303560274828, 918.9632414703337, 970.7912798748303, 17607.735883961752, 18733.531661708566, 18785.7182142268, 19429.805701942798, 19596.412107636792, 965.5751469353462, 971.3734641156556, 972.1056627902346, 1016.0702956794598, 1036.2073014144187, 10277.537510812032, 10842.403667798348, 10916.235863489479, 10937.617183398543, 10940.0, 468332.0, 468834.0, 474144.0, 479131.0, 480264.0, 99467.0, 101409.49295253477, 101548.49225753972, 103719.0, 103808.0, 1014.660088870222, 1055.1400608504046, 1058.0953284941418, 1095.6498311122598, 1099.2525082943675, 17192.22634981404, 17542.12289385551, 18030.72953905666, 18039.933211354364, 18404.723929140804, 41887.790561047, 42544.78727606342, 43753.34369984389, 43834.56165438432, 44001.33997989968, 55375.0, 60939.69530152321, 62130.0, 62648.68675656593, 64210.67894660497, 8072.0, 8234.917650823416, 8590.82818343642, 8626.82746345057, 8634.30925525969, 12466.5013399463, 12796.680082998, 12883.677908052374, 12996.02529810287, 13106.803397949228, 10938.23432359759, 11714.824277636013, 11748.17762756633, 11969.10231732641, 12271.07966902468, 10899.945500272448, 11235.269707468988, 11476.024537914036, 11621.535138594361, 11820.290782553075, 11419.828702569303, 11678.532858685558, 11715.824262635897, 12167.148299618942, 12272.877271227173, 7444.627768611656, 7767.805804854924, 8452.0, 8583.484990900566, 8904.86642700373, 11020.834687479533, 11378.829317560409, 11442.771144576895, 11533.653990380302, 11985.460654270753], "value": 9108.757351436681, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.7292505036467, 1403.2773121842251, 1444.7471692453819, 1689.9408520701777, 1759.868009899258, 7609.505382150171, 7824.413169012319, 8652.567371631418, 9324.067593240672, 9332.393394429357, 8603.311735061196, 8885.91114088859, 8893.421927574704, 9114.726558203249, 9117.0427105154, 2434.1967150840223, 2454.104251948044, 2458.50829834033, 2461.6061430171176, 2470.0, 2102.2642075273607, 2156.6872803443493, 2388.021360786847, 2475.938101547467, 2477.950440991179, 2308.272894038381, 2385.9284221473295, 2390.3904504351453, 2396.9985062472856, 2408.1932552594903, 2415.0581273303424, 2429.2226487523913, 2458.4714286428502, 2484.043643197364, 2487.1046423287603, 2454.4109413740575, 2460.8400453970435, 2466.938326541833, 2469.1851688942606, 2477.2939712182124, 8999.82000359989, 9180.403273787182, 9200.217981471584, 9201.677941272023, 9203.585838637277, 5444.6188766786345, 5609.102543593006, 5614.382417934024, 5616.5787565932715, 5622.550195984311, 3368.3936891359544, 3555.3600351936643, 3586.6234045425376, 3603.0992251937014, 3624.2207925295925, 1177.5113327968882, 1236.1779416687891, 1237.2267332916927, 2160.8055275025167, 2200.1529411176653, 12693.11148219625, 12819.230846149261, 12837.87162128376, 12840.036997225152, 12843.422046007952, 4445.244308467568, 4627.190241707688, 4755.476897541268, 4786.210275304606, 4833.154198015333, 9963.601455941825, 9967.850482242773, 9971.601135954481, 9973.301868869255, 9975.351602145982, 5385.8114965976, 5386.272853164851, 5388.784448622011, 5391.16436952273, 5398.1902714593125, 4991.554138472123, 5020.59835213182, 5031.22016087507, 5044.0920634286185, 5067.594592432597, 3654.8720794772057, 3718.9628103719156, 3729.4778730977687, 3754.3992961126637, 3823.8279277432584, 996.1532697207316, 1026.3072426112494, 1112.8942750438696, 1120.0815331428314, 1146.3694967767694, 808.9919100809034, 936.442816524167, 961.4519723757392, 969.413504829582, 1009.8384258518602, 5679.4320567943705, 5919.408059194132, 6087.178230938766, 6283.214598175228, 6358.491320694333, 914.9588268527932, 923.8429466990493, 947.3415975896686, 951.3911096897991, 989.8960609135937, 3977.065389633434, 4167.166446763415, 4167.291560434673, 4237.809298581571, 4306.923269182705, 11415.714607134722, 11796.823047654292, 11808.70478238051, 11811.232269902428, 11812.822807657893, 11571.884281157249, 11645.0, 11691.532338706527, 11692.532298707956, 11784.0, 8350.248477637042, 8486.575671216415, 8595.140485951355, 8605.655773769104, 8621.482711037357, 6099.512039036777, 6123.203983482119, 6129.570930034854, 6211.751529938751, 6227.626342419469, 10333.689989300334, 10426.322289051188, 10461.215408844519, 10498.265121441425, 10531.57873685044, 10489.0, 10541.578336866747, 10737.570497180026, 10751.946240268748, 10903.0, 7585.886211706939, 7790.454668173174, 7791.961040194763, 7795.0, 7795.454318197899, 8784.385093043426, 8787.253083487974, 8862.42394244372, 8870.866936996079, 8919.910800892168, 6370.713317900798, 7896.9605151976175, 7964.681412743425, 7975.0, 8020.598970051357, 7674.501157424749, 7827.960860195891, 7869.763907082796, 7895.0, 7953.6818527260575, 6762.797116086525, 6795.0, 6844.2129155147795, 6851.794446166623, 6851.794446166623, 4830.154722923404, 5111.131107711623, 5159.742012899265, 5245.763940622604, 5250.081235783646, 5183.092958732132, 5188.533032027136, 5233.267342572119, 5265.2365407015, 5429.077056900415], "value": 4759.985641783481, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.7063372331179, 329.2985939947911, 337.64041296019747, 338.30647173294756, 339.9048266485385, 5099.923501147486, 5235.79056837727, 5269.473052694733, 5327.893442131155, 5339.679619222849, 5041.294218809369, 5149.485051494848, 5149.639525233235, 5151.562117220032, 5182.870428239286, 4110.506739191302, 4122.536499542664, 4123.9381409278885, 4132.780829655244, 4184.727992680481, 3940.4089386591954, 4140.523839758421, 4157.0, 4163.542010378856, 4172.102997855461, 4085.775282359469, 4090.650085471802, 4103.158852435244, 4107.486564179478, 4150.232207041707, 4092.8772136835646, 4096.938545921814, 4098.426220329156, 4106.630403263721, 4154.626083652486, 3674.467202255658, 3676.8529258829612, 3681.300552894968, 3684.6868016218386, 3689.760165589255, 5117.334746482933, 5135.794568217267, 5185.611079169077, 5193.766280517386, 5194.870128246786, 4657.301404789275, 4661.603763680091, 4664.603508701731, 4669.34629151919, 4673.6027437667835, 5382.865428364283, 5441.483059109379, 5467.699276539789, 5470.91793623096, 5480.314960629921, 3882.4176373544196, 3927.56796752357, 3944.881653550398, 3959.0, 3964.920701585981, 5669.546436285129, 5698.544116470673, 5713.999999999958, 5722.37053924068, 5726.68503232322, 4050.1697152083752, 4055.756654600674, 4059.79701014954, 4067.91864162721, 4075.7962101894786, 3872.5159355080614, 4098.118904435561, 4116.567760385176, 4123.793810309473, 4133.772642504691, 4671.672982891233, 4683.81264749413, 4684.648651351163, 4685.672002959828, 4741.316832524473, 5568.359638641527, 5579.553635709133, 5583.302087239095, 5657.660540367591, 5661.915071273934, 1316.9539066132638, 1341.8658134186503, 1395.1350162899068, 1413.4204975959808, 1421.1686163594413, 891.741394995444, 897.1028971028971, 913.3834661603391, 927.526961249754, 929.1126973740062, 4778.845961665284, 4788.257820037899, 4813.638977076734, 4822.54050434274, 4853.296272040534, 4939.925901111486, 4942.505749425029, 4973.875653108701, 5022.392834292991, 5027.296178535008, 4512.729236245836, 4700.835470758576, 4718.4664983880375, 4719.95280047202, 4722.740249286322, 2599.740025997385, 2600.388908606476, 2601.5187190369843, 2615.2415799418336, 2621.239840446249, 5045.823396181116, 5052.924206136838, 5109.233614957711, 5135.486451354909, 5136.743162841918, 4776.423780152541, 5028.723420211923, 5059.519345662156, 5060.7216603086445, 5122.359705036871, 5034.572061374751, 5103.8468845934685, 5122.513361230752, 5124.538791508782, 5165.0, 4908.950910490849, 4930.362256193063, 4940.901181976412, 4954.702717836942, 5039.949600503948, 4599.241125214436, 4924.655274130777, 4927.285543596086, 4931.48219436954, 4961.479044700254, 5064.151245053712, 5100.63358183597, 5105.234214867651, 5120.590352771844, 5144.30551875492, 3673.357162496499, 3682.558093028854, 3682.8526858925343, 3736.140687641803, 3747.494088298045, 4888.364512613338, 5025.899482010413, 5028.522290382408, 5070.881407934167, 5461.1808228766, 5489.423610520837, 5511.311086114236, 5570.58220633446, 5578.163275508706, 5595.804146854841, 5564.360098588714, 5569.0, 5598.068666310117, 5615.3823079462045, 5656.264685590847, 5257.658252213594, 5533.3083364579425, 5533.667979921218, 5536.4186760389575, 5562.0, 5628.015097358028, 5642.23829782974, 5646.209530665628, 5646.491815736604, 5654.802081927113, 5531.612787104864, 5534.39121696605, 5547.278853748987, 5601.5238704710555, 5615.943840561542], "value": 4048.725913843031, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.5817951049862, 728.2389902551832, 778.7741554949065, 819.4468733604815, 827.4184544222028, 14113.0, 15120.924395378035, 15121.546353609408, 15148.545543633694, 15149.697006059885, 13496.595102146926, 14185.574432767007, 14340.42638294467, 14396.0, 14426.927865360685, 784.7724159993595, 800.1158719614829, 805.9556724380186, 806.7257132574953, 807.1807115777491, 658.8221180281321, 670.0752960913957, 782.3897360059163, 798.7763426240662, 805.9435839491209, 846.4328899637258, 847.3306088190322, 847.7456762971115, 847.7753395350206, 848.6350869126273, 838.0362583029504, 838.4172999765166, 838.6687258532845, 838.7190291252438, 839.0057781528877, 505.5475349562158, 514.5934711577886, 595.2262059322907, 607.1318015238252, 619.6529943231805, 14204.715905681931, 14223.786643200361, 14235.644108897257, 14264.35810388535, 14268.357923893449, 12848.293343866084, 13520.729585408237, 13625.386857591433, 13656.93171534136, 13823.308834558231, 11593.304401735923, 11871.821922671166, 11883.940580297043, 12157.696057598543, 12268.0, 4396.912061758779, 4419.712718673276, 4546.477155127137, 4647.535246475325, 4689.507601701806, 79945.0, 81072.59463702702, 81208.9999999994, 82603.0, 85899.5705021471, 10806.675799726021, 10959.50682219302, 11034.779304414027, 11091.889081109086, 11235.66293011211, 20029.39911802619, 20052.0, 20070.698939515918, 20083.598328033357, 20103.597928041356, 8221.136780638066, 8289.336853051742, 8369.079401265855, 8390.077091519928, 8488.83022339562, 4602.332661764025, 4808.206645903457, 4840.878978025577, 4888.120138375127, 5009.073321435546, 3367.983160084184, 3485.6514348564942, 3486.3201675673, 3488.860445582199, 3500.0024992876906, 588.6791698524314, 594.910763385487, 595.5771402304413, 597.6025942748024, 602.3524710935761, 587.3011116771029, 620.3083561828529, 632.7342516143231, 655.3773914780977, 691.3466773898718, 9132.863007054899, 9179.816403672023, 9715.757106072404, 9800.313978021613, 9918.80162396748, 606.6693651959604, 621.6301300726061, 631.2141383976978, 636.4017823246134, 657.8552718401944, 4238.830446782094, 4326.58897404746, 4373.190959672471, 4456.821727130879, 4616.146012987608, 102995.4850225744, 125954.0, 128422.0, 130755.0, 133131.3343433296, 109359.0, 112789.4360528192, 114350.42824785823, 114498.42750786192, 117920.41039794746, 96777.51611241899, 97472.0, 98137.50931245298, 101440.0, 103002.0, 8040.919590804251, 8287.12985136552, 8385.874211886949, 8450.154984501502, 8452.619632116692, 57101.0, 57350.7132464335, 57675.711621441624, 57968.71015644895, 62161.0, 76070.61964690141, 78919.0, 82831.71682831668, 83716.58141709253, 84595.57702211695, 11501.769964600828, 11734.471948762497, 11850.407479626158, 12556.969591313446, 12711.216396539738, 11860.76278474443, 11912.761744764883, 12117.69705757328, 12259.141860069709, 12855.935720321713, 12255.938720306342, 12288.207599036205, 12421.19262247951, 12608.810867837174, 12715.745685086062, 12653.936730316289, 12727.0, 12734.172278801849, 12735.936320318338, 12764.744705106032, 10743.715031518599, 11274.887251127711, 11289.20975531705, 11341.0, 11438.94280528592, 9268.0, 9445.905540944503, 9703.272254581076, 10265.538050787882, 10356.689299321033, 8795.91204087951, 10213.693589192337, 10288.148133336072, 10386.844197336895, 10391.220658450799], "value": 7345.802184094713, "unit": "score"}], "name": "dromaeo_css", "value": 7136.745105459054, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 114.0, 111.0, 112.0, 113.0, 114.0, 112.0, 113.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 117.0, 119.0, 165.0, 122.0, 120.0, 123.0, 118.0, 145.0, 118.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 163.0, 164.0, 162.0, 164.0, 162.0, 160.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 107.0, 77.0, 76.0, 75.0, 76.0, 102.0, 76.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 106.0, 83.0, 82.0, 82.0, 114.0, 83.0, 83.0, 109.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 118.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 49.0, 50.0, 51.0, 51.0, 51.0, 51.0, 52.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 62.0, 61.0, 62.0, 61.0, 61.0, 61.0, 66.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 78.0, 79.0, 82.0, 85.0, 83.0, 79.0, 78.0, 79.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 126.0, 124.0, 128.0, 137.0, 122.0, 125.0, 126.0, 160.0, 127.0], "value": 130.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 165.0, 164.0, 165.0, 164.0, 165.0, 171.0, 164.0, 162.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 64.0, 63.0, 64.0, 65.0, 66.0, 66.0, 64.0], "value": 64.8, "unit": "score"}], "name": "kraken", "value": 1486.6, "alertThreshold": 2.0}]} 06:12:51 INFO - Return code: 0 06:12:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:12:51 INFO - # TBPL SUCCESS # 06:12:51 INFO - Running post-action listener: _resource_record_post_action 06:12:51 INFO - [mozharness: 2016-09-21 13:12:51.424963Z] Finished run-tests step (success) 06:12:51 INFO - Running post-run listener: _resource_record_post_run 06:12:51 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393666560; Read time: 8; Write time: 257944 06:12:51 INFO - TinderboxPrint: CPU usage
13.2% 06:12:51 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 06:12:51 INFO - TinderboxPrint: I/O write bytes / time
393,666,560 / 257,944 06:12:51 INFO - TinderboxPrint: CPU idle
8,271.2 (86.8%) 06:12:51 INFO - TinderboxPrint: CPU user
1,202.6 (12.6%) 06:12:51 INFO - TinderboxPrint: Swap in / out
0 / 0 06:12:51 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103727104; Read time: 0; Write time: 91340 06:12:51 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 289939456; Read time: 0; Write time: 166604 06:12:52 INFO - Running post-run listener: _upload_blobber_files 06:12:52 INFO - Blob upload gear active. 06:12:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:12:52 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 06:12:52 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'] 06:12:52 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 06:12:52 INFO - (blobuploader) - INFO - Open directory for files ... 06:12:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:12:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:12:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:53 INFO - (blobuploader) - INFO - Done attempting. 06:12:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:12:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:12:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:57 INFO - (blobuploader) - INFO - Done attempting. 06:12:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:12:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:12:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:12:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:12:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:12:58 INFO - (blobuploader) - INFO - Done attempting. 06:12:58 INFO - (blobuploader) - INFO - Iteration through files over. 06:12:58 INFO - Return code: 0 06:12:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:12:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:12:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd551a239645775f718ebe14a2a950f07802eac2064721099f233e30932a6b98111c98cd900e70b212b4397568b9451f8df3160cb964239e45d971aaf9d12730", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/871499170f7ae30d038fbcbcc25574948d0b1f75c709b77970f6382f5079e349fa5b127256279e5ca8bdc734c0178e0eecc77b7faa161e53975a5b63e29c1ac5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6de88258934e1af24ae7aa1d412a50fe6d7ae71f16ec76d2308f4d26b1a24f854b9109298d7ad1c901bb3f4004a7d179093a631d401db2f4469182fd3e7d28e4"} 06:12:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:12:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:12:58 INFO - Contents: 06:12:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd551a239645775f718ebe14a2a950f07802eac2064721099f233e30932a6b98111c98cd900e70b212b4397568b9451f8df3160cb964239e45d971aaf9d12730", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/871499170f7ae30d038fbcbcc25574948d0b1f75c709b77970f6382f5079e349fa5b127256279e5ca8bdc734c0178e0eecc77b7faa161e53975a5b63e29c1ac5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6de88258934e1af24ae7aa1d412a50fe6d7ae71f16ec76d2308f4d26b1a24f854b9109298d7ad1c901bb3f4004a7d179093a631d401db2f4469182fd3e7d28e4"} 06:12:58 INFO - Running post-run listener: copy_logs_to_upload_dir 06:12:58 INFO - Copying logs to upload dir... 06:12:58 INFO - mkdir: /builds/slave/test/build/upload/logs 06:12:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.450911 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-21 06:12:57.675947) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 06:12:57.679042) ========= 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=6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975 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/bd551a239645775f718ebe14a2a950f07802eac2064721099f233e30932a6b98111c98cd900e70b212b4397568b9451f8df3160cb964239e45d971aaf9d12730", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/871499170f7ae30d038fbcbcc25574948d0b1f75c709b77970f6382f5079e349fa5b127256279e5ca8bdc734c0178e0eecc77b7faa161e53975a5b63e29c1ac5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6de88258934e1af24ae7aa1d412a50fe6d7ae71f16ec76d2308f4d26b1a24f854b9109298d7ad1c901bb3f4004a7d179093a631d401db2f4469182fd3e7d28e4"} build_url:https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011442 build_url: 'https://queue.taskcluster.net/v1/task/Z7A03dzBSQiQqsvj6cHvlw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd551a239645775f718ebe14a2a950f07802eac2064721099f233e30932a6b98111c98cd900e70b212b4397568b9451f8df3160cb964239e45d971aaf9d12730", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/871499170f7ae30d038fbcbcc25574948d0b1f75c709b77970f6382f5079e349fa5b127256279e5ca8bdc734c0178e0eecc77b7faa161e53975a5b63e29c1ac5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6de88258934e1af24ae7aa1d412a50fe6d7ae71f16ec76d2308f4d26b1a24f854b9109298d7ad1c901bb3f4004a7d179093a631d401db2f4469182fd3e7d28e4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 06:12:57.707962) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 06:12:57.708705) ========= 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=6dbc10da6d83fee962c624be000001a8-1474462337.248211-423736975 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004386 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 06:12:57.792348) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 06:12:57.792738) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 06:12:57.793076) ========= ========= Total master_lag: 0.18 =========