builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-018 starttime: 1474441464.51 results: success (0) buildid: 20160920233206 builduid: bfa25699c6e64a4bbbc174344ea02596 revision: 36373fbb78111d4fe0af105056d5066d1a669739 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.506902) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.507399) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.507718) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.545296) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.545817) ========= 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=62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 00:04:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2016-09-21 00:04:24 (45.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117224 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.677777) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.678241) ========= 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=62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019639 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.728685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:24.729042) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 36373fbb78111d4fe0af105056d5066d1a669739 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 36373fbb78111d4fe0af105056d5066d1a669739 --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=62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 00:04:24,773 truncating revision to first 12 chars 2016-09-21 00:04:24,773 Setting DEBUG logging. 2016-09-21 00:04:24,773 attempt 1/10 2016-09-21 00:04:24,773 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/36373fbb7811?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 00:04:25,329 unpacking tar archive at: fx-team-36373fbb7811/testing/mozharness/ program finished with exit code 0 elapsedTime=0.955981 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:25.699495) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:25.699833) ========= script_repo_revision: 36373fbb78111d4fe0af105056d5066d1a669739 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:25.700244) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:25.700513) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 00:04:25.715605) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-21 00:04:25.715888) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:04:25 INFO - MultiFileLogger online at 20160921 00:04:25 in /builds/slave/test 00:04:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 00:04:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:04:25 INFO - {'append_to_log': False, 00:04:25 INFO - 'base_work_dir': '/builds/slave/test', 00:04:25 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 00:04:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:04:25 INFO - 'branch': 'Fx-Team-Non-PGO', 00:04:25 INFO - 'buildbot_json_path': 'buildprops.json', 00:04:25 INFO - 'config_files': ('talos/linux_config.py',), 00:04:25 INFO - 'default_actions': ('clobber', 00:04:25 INFO - 'read-buildbot-config', 00:04:25 INFO - 'download-and-extract', 00:04:25 INFO - 'populate-webroot', 00:04:25 INFO - 'create-virtualenv', 00:04:25 INFO - 'install', 00:04:25 INFO - 'run-tests'), 00:04:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:04:25 INFO - 'download_minidump_stackwalk': True, 00:04:25 INFO - 'download_symbols': 'ondemand', 00:04:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:04:25 INFO - 'tooltool.py': '/tools/tooltool.py', 00:04:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:04:25 INFO - '/tools/misc-python/virtualenv.py')}, 00:04:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:04:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:04:25 INFO - 'installer_path': 'installer.exe', 00:04:25 INFO - 'log_level': 'info', 00:04:25 INFO - 'log_name': 'talos', 00:04:25 INFO - 'log_to_console': True, 00:04:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:04:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:04:25 INFO - 'opt_config_files': (), 00:04:25 INFO - 'pip_index': False, 00:04:25 INFO - 'sps_profile': False, 00:04:25 INFO - 'sps_profile_interval': 0, 00:04:25 INFO - 'suite': 'dromaeojs', 00:04:25 INFO - 'system_bits': '32', 00:04:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:04:25 INFO - 'title': 'talos-linux64-ix-018', 00:04:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:04:25 INFO - 'use_talos_json': True, 00:04:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:04:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:04:25 INFO - 'work_dir': 'build'} 00:04:25 INFO - [mozharness: 2016-09-21 07:04:25.855886Z] Running clobber step. 00:04:25 INFO - Running pre-action listener: _resource_record_pre_action 00:04:25 INFO - Running main action method: clobber 00:04:25 INFO - rmtree: /builds/slave/test/build 00:04:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:04:26 INFO - Running post-action listener: _resource_record_post_action 00:04:26 INFO - [mozharness: 2016-09-21 07:04:26.307722Z] Finished clobber step (success) 00:04:26 INFO - [mozharness: 2016-09-21 07:04:26.307822Z] Running read-buildbot-config step. 00:04:26 INFO - Running pre-action listener: _resource_record_pre_action 00:04:26 INFO - Running main action method: read_buildbot_config 00:04:26 INFO - Using buildbot properties: 00:04:26 INFO - { 00:04:26 INFO - "project": "", 00:04:26 INFO - "product": "firefox", 00:04:26 INFO - "script_repo_revision": "production", 00:04:26 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 00:04:26 INFO - "repository": "", 00:04:26 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 00:04:26 INFO - "buildid": "20160920233206", 00:04:26 INFO - "pgo_build": "False", 00:04:26 INFO - "basedir": "/builds/slave/test", 00:04:26 INFO - "buildnumber": 1944, 00:04:26 INFO - "slavename": "talos-linux64-ix-018", 00:04:26 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 00:04:26 INFO - "platform": "ubuntu64_hw", 00:04:26 INFO - "scheduler": "tests-fx-team-linux64-talos", 00:04:26 INFO - "branch": "fx-team", 00:04:26 INFO - "repo_path": "integration/fx-team", 00:04:26 INFO - "revision": "36373fbb78111d4fe0af105056d5066d1a669739", 00:04:26 INFO - "stage_platform": "linux64", 00:04:26 INFO - "builduid": "bfa25699c6e64a4bbbc174344ea02596", 00:04:26 INFO - "slavebuilddir": "test" 00:04:26 INFO - } 00:04:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 00:04:26 INFO - Running post-action listener: _resource_record_post_action 00:04:26 INFO - [mozharness: 2016-09-21 07:04:26.310286Z] Finished read-buildbot-config step (success) 00:04:26 INFO - [mozharness: 2016-09-21 07:04:26.310376Z] Running download-and-extract step. 00:04:26 INFO - Running pre-action listener: _resource_record_pre_action 00:04:26 INFO - Running main action method: download_and_extract 00:04:26 INFO - mkdir: /builds/slave/test/build/tests 00:04:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:04:26 INFO - trying https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 00:04:26 INFO - Downloading https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/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 00:04:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/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 00:04:26 INFO - Downloaded 1361 bytes. 00:04:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 00:04:26 INFO - Using the following test package requirements: 00:04:26 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 00:04:26 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:04:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:04:26 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:04:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:04:26 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:04:26 INFO - u'jsshell-linux-x86_64.zip'], 00:04:26 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:04:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:04:26 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 00:04:26 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:04:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:04:26 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:04:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:04:26 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:04:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:04:26 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 00:04:26 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:04:26 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:04:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 00:04:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:04:26 INFO - Fetch https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 00:04:27 INFO - Expected file size: 20369760 00:04:31 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 00:04:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 00:04:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:04:31 INFO - Fetch https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 00:04:32 INFO - Expected file size: 20369760 00:04:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 00:04:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 00:04:36 INFO - Fetch https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 00:04:37 INFO - Expected file size: 14414059 00:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:04:38 INFO - trying https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 00:04:38 INFO - Downloading https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/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 00:04:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/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 00:04:40 INFO - Downloaded 55600060 bytes. 00:04:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 00:04:40 INFO - mkdir: /builds/slave/test/properties 00:04:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:04:40 INFO - Writing to file /builds/slave/test/properties/build_url 00:04:40 INFO - Contents: 00:04:40 INFO - build_url:https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 00:04:41 INFO - Running post-action listener: _resource_record_post_action 00:04:41 INFO - Running post-action listener: set_extra_try_arguments 00:04:41 INFO - [mozharness: 2016-09-21 07:04:41.153118Z] Finished download-and-extract step (success) 00:04:41 INFO - [mozharness: 2016-09-21 07:04:41.153386Z] Running populate-webroot step. 00:04:41 INFO - Running pre-action listener: _resource_record_pre_action 00:04:41 INFO - Running main action method: populate_webroot 00:04:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:04:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:04:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:04:41 INFO - '--tppagecycles', 00:04:41 INFO - '7'], 00:04:41 INFO - 'tests': ['tsvgm']}}, 00:04:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:04:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:04:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:04:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:04:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:41 INFO - 'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:04:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:04:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:41 INFO - 'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['damp', 'tps']}, 00:04:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:41 INFO - 'tests': ['damp', 'tps']}, 00:04:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['dromaeo_dom']}, 00:04:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:04:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['basic_compositor_video']}, 00:04:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:04:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['a11yr', 00:04:41 INFO - 'ts_paint', 00:04:41 INFO - 'tpaint', 00:04:41 INFO - 'sessionrestore', 00:04:41 INFO - 'sessionrestore_no_auto_restore', 00:04:41 INFO - 'tabpaint']}, 00:04:41 INFO - 'other-e10s': {'tests': ['a11yr', 00:04:41 INFO - 'ts_paint', 00:04:41 INFO - 'tpaint', 00:04:41 INFO - 'sessionrestore', 00:04:41 INFO - 'sessionrestore_no_auto_restore', 00:04:41 INFO - 'tabpaint']}, 00:04:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:04:41 INFO - 'ts_paint', 00:04:41 INFO - 'tpaint', 00:04:41 INFO - 'sessionrestore', 00:04:41 INFO - 'sessionrestore_no_auto_restore', 00:04:41 INFO - 'tabpaint']}, 00:04:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:04:41 INFO - 'ts_paint', 00:04:41 INFO - 'tpaint', 00:04:41 INFO - 'sessionrestore', 00:04:41 INFO - 'sessionrestore_no_auto_restore', 00:04:41 INFO - 'tabpaint']}, 00:04:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['a11yr', 00:04:41 INFO - 'ts_paint', 00:04:41 INFO - 'tpaint', 00:04:41 INFO - 'sessionrestore', 00:04:41 INFO - 'sessionrestore_no_auto_restore', 00:04:41 INFO - 'tabpaint']}, 00:04:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['a11yr', 00:04:41 INFO - 'ts_paint', 00:04:41 INFO - 'tpaint', 00:04:41 INFO - 'sessionrestore', 00:04:41 INFO - 'sessionrestore_no_auto_restore', 00:04:41 INFO - 'tabpaint']}, 00:04:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['tsvgx', 00:04:41 INFO - 'tsvgr_opacity', 00:04:41 INFO - 'tart', 00:04:41 INFO - 'tscrollx', 00:04:41 INFO - 'cart']}, 00:04:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:04:41 INFO - 'tsvgr_opacity', 00:04:41 INFO - 'tart', 00:04:41 INFO - 'tscrollx', 00:04:41 INFO - 'cart']}, 00:04:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:41 INFO - 'talos_options': ['--disable-e10s'], 00:04:41 INFO - 'tests': ['tp5o']}, 00:04:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:41 INFO - 'tests': ['tp5o']}, 00:04:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:41 INFO - 'talos_options': ['--disable-e10s', 00:04:41 INFO - '--xperf_path', 00:04:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:04:41 INFO - 'tests': ['tp5n']}, 00:04:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:04:41 INFO - 'talos_options': ['--xperf_path', 00:04:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:04:41 INFO - 'tests': ['tp5n']}}, 00:04:41 INFO - 'talos.zip': {'path': '', 00:04:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:04:41 INFO - Running post-action listener: _resource_record_post_action 00:04:41 INFO - [mozharness: 2016-09-21 07:04:41.174394Z] Finished populate-webroot step (success) 00:04:41 INFO - [mozharness: 2016-09-21 07:04:41.174483Z] Running create-virtualenv step. 00:04:41 INFO - Running pre-action listener: _resource_record_pre_action 00:04:41 INFO - Running main action method: create_virtualenv 00:04:41 INFO - Creating virtualenv /builds/slave/test/build/venv 00:04:41 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 00:04:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:04:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:04:41 INFO - Using real prefix '/usr' 00:04:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:04:41 INFO - Installing distribute.............................................................................................................................................................................................done. 00:04:43 INFO - Installing pip.................done. 00:04:43 INFO - Return code: 0 00:04:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:04:43 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')]} 00:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:43 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')]} 00:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:43 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:04:43 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 00:04:43 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 00:04:43 INFO - Using env: {'DISPLAY': ':0', 00:04:43 INFO - 'HOME': '/home/cltbld', 00:04:43 INFO - 'LANG': 'en_US.UTF-8', 00:04:43 INFO - 'LANGUAGE': 'en_US:en', 00:04:43 INFO - 'LOGNAME': 'cltbld', 00:04:43 INFO - 'MAIL': '/var/mail/cltbld', 00:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:43 INFO - 'MOZ_NO_REMOTE': '1', 00:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:43 INFO - 'NO_EM_RESTART': '1', 00:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:43 INFO - 'PWD': '/builds/slave/test', 00:04:43 INFO - 'SHELL': '/bin/bash', 00:04:43 INFO - 'SHLVL': '1', 00:04:43 INFO - 'TERM': 'linux', 00:04:43 INFO - 'TMOUT': '86400', 00:04:43 INFO - 'USER': 'cltbld', 00:04:43 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:43 INFO - '_': '/tools/buildbot/bin/python'} 00:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:43 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 00:04:43 INFO - Cleaning up... 00:04:43 INFO - Return code: 0 00:04:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:04:43 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')]} 00:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:43 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')]} 00:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:43 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:04:43 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 00:04:43 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 00:04:43 INFO - Using env: {'DISPLAY': ':0', 00:04:43 INFO - 'HOME': '/home/cltbld', 00:04:43 INFO - 'LANG': 'en_US.UTF-8', 00:04:43 INFO - 'LANGUAGE': 'en_US:en', 00:04:43 INFO - 'LOGNAME': 'cltbld', 00:04:43 INFO - 'MAIL': '/var/mail/cltbld', 00:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:43 INFO - 'MOZ_NO_REMOTE': '1', 00:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:43 INFO - 'NO_EM_RESTART': '1', 00:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:43 INFO - 'PWD': '/builds/slave/test', 00:04:43 INFO - 'SHELL': '/bin/bash', 00:04:43 INFO - 'SHLVL': '1', 00:04:43 INFO - 'TERM': 'linux', 00:04:43 INFO - 'TMOUT': '86400', 00:04:43 INFO - 'USER': 'cltbld', 00:04:43 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:43 INFO - '_': '/tools/buildbot/bin/python'} 00:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:44 INFO - Downloading/unpacking psutil>=3.1.1 00:04: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 00:04: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 00:04: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 00:04: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 00:04: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 00:04: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 00:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:04:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:04:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:04:46 INFO - Installing collected packages: psutil 00:04:46 INFO - Running setup.py install for psutil 00:04:46 INFO - building 'psutil._psutil_linux' extension 00:04:46 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 00:04:46 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 00:04:46 INFO - building 'psutil._psutil_posix' extension 00:04:46 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 00:04:46 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 00:04:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:04:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:04:46 INFO - Successfully installed psutil 00:04:46 INFO - Cleaning up... 00:04:46 INFO - Return code: 0 00:04:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:04:46 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')]} 00:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:46 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')]} 00:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:46 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:04:46 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 00:04:46 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 00:04:46 INFO - Using env: {'DISPLAY': ':0', 00:04:46 INFO - 'HOME': '/home/cltbld', 00:04:46 INFO - 'LANG': 'en_US.UTF-8', 00:04:46 INFO - 'LANGUAGE': 'en_US:en', 00:04:46 INFO - 'LOGNAME': 'cltbld', 00:04:46 INFO - 'MAIL': '/var/mail/cltbld', 00:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:46 INFO - 'MOZ_NO_REMOTE': '1', 00:04:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:46 INFO - 'NO_EM_RESTART': '1', 00:04:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:46 INFO - 'PWD': '/builds/slave/test', 00:04:46 INFO - 'SHELL': '/bin/bash', 00:04:46 INFO - 'SHLVL': '1', 00:04:46 INFO - 'TERM': 'linux', 00:04:46 INFO - 'TMOUT': '86400', 00:04:46 INFO - 'USER': 'cltbld', 00:04:46 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:46 INFO - '_': '/tools/buildbot/bin/python'} 00:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:04:48 INFO - Installing collected packages: mozsystemmonitor 00:04:48 INFO - Running setup.py install for mozsystemmonitor 00:04:48 INFO - Successfully installed mozsystemmonitor 00:04:48 INFO - Cleaning up... 00:04:49 INFO - Return code: 0 00:04:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:04: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')]} 00:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04: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')]} 00:04:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:04:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 00:04:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 00:04:49 INFO - Using env: {'DISPLAY': ':0', 00:04:49 INFO - 'HOME': '/home/cltbld', 00:04:49 INFO - 'LANG': 'en_US.UTF-8', 00:04:49 INFO - 'LANGUAGE': 'en_US:en', 00:04:49 INFO - 'LOGNAME': 'cltbld', 00:04:49 INFO - 'MAIL': '/var/mail/cltbld', 00:04:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:49 INFO - 'MOZ_NO_REMOTE': '1', 00:04:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:49 INFO - 'NO_EM_RESTART': '1', 00:04:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:49 INFO - 'PWD': '/builds/slave/test', 00:04:49 INFO - 'SHELL': '/bin/bash', 00:04:49 INFO - 'SHLVL': '1', 00:04:49 INFO - 'TERM': 'linux', 00:04:49 INFO - 'TMOUT': '86400', 00:04:49 INFO - 'USER': 'cltbld', 00:04:49 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:04:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:49 INFO - '_': '/tools/buildbot/bin/python'} 00:04:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:49 INFO - Downloading/unpacking jsonschema==2.5.1 00:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:04:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:04:51 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 00:04:51 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 00:04:51 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 00:04:51 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 00:04:51 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 00:04:51 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 00:04:51 INFO - Downloading functools32-3.2.3-2.tar.gz 00:04:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:04:51 INFO - warning: no files found matching '*.txt' 00:04:51 INFO - no previously-included directories found matching 'build' 00:04:51 INFO - no previously-included directories found matching 'dist' 00:04:51 INFO - no previously-included directories found matching '.git*' 00:04:51 INFO - Installing collected packages: jsonschema, functools32 00:04:51 INFO - Running setup.py install for functools32 00:04:51 INFO - warning: no files found matching '*.txt' 00:04:51 INFO - no previously-included directories found matching 'build' 00:04:51 INFO - no previously-included directories found matching 'dist' 00:04:51 INFO - no previously-included directories found matching '.git*' 00:04:51 INFO - Successfully installed jsonschema functools32 00:04:51 INFO - Cleaning up... 00:04:51 INFO - Return code: 0 00:04:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:04:51 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')]} 00:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:51 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')]} 00:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:51 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:04:51 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 00:04:51 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 00:04:51 INFO - Using env: {'DISPLAY': ':0', 00:04:51 INFO - 'HOME': '/home/cltbld', 00:04:51 INFO - 'LANG': 'en_US.UTF-8', 00:04:51 INFO - 'LANGUAGE': 'en_US:en', 00:04:51 INFO - 'LOGNAME': 'cltbld', 00:04:51 INFO - 'MAIL': '/var/mail/cltbld', 00:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:51 INFO - 'MOZ_NO_REMOTE': '1', 00:04:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:51 INFO - 'NO_EM_RESTART': '1', 00:04:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:51 INFO - 'PWD': '/builds/slave/test', 00:04:51 INFO - 'SHELL': '/bin/bash', 00:04:51 INFO - 'SHLVL': '1', 00:04:51 INFO - 'TERM': 'linux', 00:04:51 INFO - 'TMOUT': '86400', 00:04:51 INFO - 'USER': 'cltbld', 00:04:51 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:51 INFO - '_': '/tools/buildbot/bin/python'} 00:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:04:51 INFO - Cleaning up... 00:04:51 INFO - Return code: 0 00:04:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:04:51 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')]} 00:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:51 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')]} 00:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:51 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:04:51 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 00:04:51 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 00:04:51 INFO - Using env: {'DISPLAY': ':0', 00:04:51 INFO - 'HOME': '/home/cltbld', 00:04:51 INFO - 'LANG': 'en_US.UTF-8', 00:04:51 INFO - 'LANGUAGE': 'en_US:en', 00:04:51 INFO - 'LOGNAME': 'cltbld', 00:04:51 INFO - 'MAIL': '/var/mail/cltbld', 00:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:51 INFO - 'MOZ_NO_REMOTE': '1', 00:04:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:51 INFO - 'NO_EM_RESTART': '1', 00:04:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:51 INFO - 'PWD': '/builds/slave/test', 00:04:51 INFO - 'SHELL': '/bin/bash', 00:04:51 INFO - 'SHLVL': '1', 00:04:51 INFO - 'TERM': 'linux', 00:04:51 INFO - 'TMOUT': '86400', 00:04:51 INFO - 'USER': 'cltbld', 00:04:51 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:51 INFO - '_': '/tools/buildbot/bin/python'} 00:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:52 INFO - Downloading/unpacking blobuploader==1.2.4 00:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:53 INFO - Downloading blobuploader-1.2.4.tar.gz 00:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:04:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:04: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 00:04: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 00:04: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 00:04: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 00:04: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 00:04: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 00:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:04:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:04:54 INFO - Downloading docopt-0.6.1.tar.gz 00:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:04:54 INFO - Installing collected packages: blobuploader, requests, docopt 00:04:54 INFO - Running setup.py install for blobuploader 00:04:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:04:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:04:54 INFO - Running setup.py install for requests 00:04:55 INFO - Running setup.py install for docopt 00:04:55 INFO - Successfully installed blobuploader requests docopt 00:04:55 INFO - Cleaning up... 00:04:55 INFO - Return code: 0 00:04:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:04:55 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')]} 00:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:55 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')]} 00:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:55 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:04:55 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 00:04:55 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 00:04:55 INFO - Using env: {'DISPLAY': ':0', 00:04:55 INFO - 'HOME': '/home/cltbld', 00:04:55 INFO - 'LANG': 'en_US.UTF-8', 00:04:55 INFO - 'LANGUAGE': 'en_US:en', 00:04:55 INFO - 'LOGNAME': 'cltbld', 00:04:55 INFO - 'MAIL': '/var/mail/cltbld', 00:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:55 INFO - 'MOZ_NO_REMOTE': '1', 00:04:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:55 INFO - 'NO_EM_RESTART': '1', 00:04:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:55 INFO - 'PWD': '/builds/slave/test', 00:04:55 INFO - 'SHELL': '/bin/bash', 00:04:55 INFO - 'SHLVL': '1', 00:04:55 INFO - 'TERM': 'linux', 00:04:55 INFO - 'TMOUT': '86400', 00:04:55 INFO - 'USER': 'cltbld', 00:04:55 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:55 INFO - '_': '/tools/buildbot/bin/python'} 00:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:04:55 INFO - Running setup.py (path:/tmp/pip-Oq_xBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:04:55 INFO - Running setup.py (path:/tmp/pip-F3KJmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:04:55 INFO - Running setup.py (path:/tmp/pip-iFMaJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:04:55 INFO - Running setup.py (path:/tmp/pip-J95mW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:04:55 INFO - Running setup.py (path:/tmp/pip-y9EOtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:04:55 INFO - Running setup.py (path:/tmp/pip-d5Wwqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:04:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:04:55 INFO - Running setup.py (path:/tmp/pip-gYfZ22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:04:56 INFO - Running setup.py (path:/tmp/pip-tr4kUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:04:56 INFO - Running setup.py (path:/tmp/pip-zR088a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:04:56 INFO - Running setup.py (path:/tmp/pip-L3qDSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:04:56 INFO - Running setup.py (path:/tmp/pip-nVw2Oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:04:56 INFO - Running setup.py (path:/tmp/pip-OHq9cW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:04:56 INFO - Running setup.py (path:/tmp/pip-4zXtQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:04:56 INFO - Running setup.py (path:/tmp/pip-OThlME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:04:56 INFO - Running setup.py (path:/tmp/pip-S69b1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:04:56 INFO - Running setup.py (path:/tmp/pip-Edq3o2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:04:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:04:56 INFO - Running setup.py (path:/tmp/pip-dKafTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:04:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:04:56 INFO - Running setup.py install for manifestparser 00:04:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:04:56 INFO - Running setup.py install for mozcrash 00:04:56 INFO - Running setup.py install for mozdebug 00:04:57 INFO - Running setup.py install for mozdevice 00:04:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Running setup.py install for mozfile 00:04:57 INFO - Running setup.py install for mozhttpd 00:04:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Running setup.py install for mozinfo 00:04:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Running setup.py install for mozInstall 00:04:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Running setup.py install for mozleak 00:04:57 INFO - Running setup.py install for mozlog 00:04:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Running setup.py install for moznetwork 00:04:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:04:57 INFO - Running setup.py install for mozprocess 00:04:57 INFO - Running setup.py install for mozprofile 00:04:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:04:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:04:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:04:58 INFO - Running setup.py install for mozrunner 00:04:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:04:58 INFO - Running setup.py install for mozscreenshot 00:04:58 INFO - Running setup.py install for moztest 00:04:58 INFO - Running setup.py install for mozversion 00:04:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:04:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:04:58 INFO - Cleaning up... 00:04:58 INFO - Return code: 0 00:04:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:04:58 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')]} 00:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:58 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')]} 00:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:04:58 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:04:58 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 00:04:58 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 00:04:58 INFO - Using env: {'DISPLAY': ':0', 00:04:58 INFO - 'HOME': '/home/cltbld', 00:04:58 INFO - 'LANG': 'en_US.UTF-8', 00:04:58 INFO - 'LANGUAGE': 'en_US:en', 00:04:58 INFO - 'LOGNAME': 'cltbld', 00:04:58 INFO - 'MAIL': '/var/mail/cltbld', 00:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:04:58 INFO - 'MOZ_NO_REMOTE': '1', 00:04:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:04:58 INFO - 'NO_EM_RESTART': '1', 00:04:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:04:58 INFO - 'PWD': '/builds/slave/test', 00:04:58 INFO - 'SHELL': '/bin/bash', 00:04:58 INFO - 'SHLVL': '1', 00:04:58 INFO - 'TERM': 'linux', 00:04:58 INFO - 'TMOUT': '86400', 00:04:58 INFO - 'USER': 'cltbld', 00:04:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:04:58 INFO - '_': '/tools/buildbot/bin/python'} 00:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:04:58 INFO - Running setup.py (path:/tmp/pip-1ux7lS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:04:58 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)) 00:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:04:58 INFO - Running setup.py (path:/tmp/pip-PPznlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:04:58 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)) 00:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:04:58 INFO - Running setup.py (path:/tmp/pip-2rOOQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:04:58 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)) 00:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:04:58 INFO - Running setup.py (path:/tmp/pip-yfAujt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:04:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:04:59 INFO - Running setup.py (path:/tmp/pip-psg5yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:04:59 INFO - Running setup.py (path:/tmp/pip-A2HPOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:04:59 INFO - Running setup.py (path:/tmp/pip-lHs4p2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:04:59 INFO - Running setup.py (path:/tmp/pip-3OPCYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:04:59 INFO - Running setup.py (path:/tmp/pip-gXh6BG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:04:59 INFO - Running setup.py (path:/tmp/pip-zxJMNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:04:59 INFO - Running setup.py (path:/tmp/pip-S5xmML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:04:59 INFO - Running setup.py (path:/tmp/pip-Lx7H0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:04:59 INFO - Running setup.py (path:/tmp/pip-ly8nnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:04:59 INFO - Running setup.py (path:/tmp/pip-DOkLyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:04:59 INFO - Running setup.py (path:/tmp/pip-DbD6WR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:04:59 INFO - Running setup.py (path:/tmp/pip-7b__0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:04:59 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)) 00:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:04:59 INFO - Running setup.py (path:/tmp/pip-5UjjD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:04:59 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)) 00:04:59 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)) 00:04:59 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)) 00:04:59 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)) 00:04:59 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)) 00:04:59 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)) 00:04:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:04:59 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 00:04:59 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 00:04:59 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 00:04:59 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 00:04:59 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 00:04:59 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 00:05:01 INFO - Downloading blessings-1.6.tar.gz 00:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:05:01 INFO - Installing collected packages: blessings 00:05:01 INFO - Running setup.py install for blessings 00:05:02 INFO - Successfully installed blessings 00:05:02 INFO - Cleaning up... 00:05:02 INFO - Return code: 0 00:05:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:05:02 INFO - Reading from file tmpfile_stdout 00:05:02 INFO - Current package versions: 00:05:02 INFO - argparse == 1.2.1 00:05:02 INFO - blessings == 1.6 00:05:02 INFO - blobuploader == 1.2.4 00:05:02 INFO - docopt == 0.6.1 00:05:02 INFO - functools32 == 3.2.3-2 00:05:02 INFO - jsonschema == 2.5.1 00:05:02 INFO - manifestparser == 1.1 00:05:02 INFO - mozInstall == 1.12 00:05:02 INFO - mozcrash == 0.17 00:05:02 INFO - mozdebug == 0.1 00:05:02 INFO - mozdevice == 0.48 00:05:02 INFO - mozfile == 1.2 00:05:02 INFO - mozhttpd == 0.7 00:05:02 INFO - mozinfo == 0.9 00:05:02 INFO - mozleak == 0.1 00:05:02 INFO - mozlog == 3.4 00:05:02 INFO - moznetwork == 0.27 00:05:02 INFO - mozprocess == 0.23 00:05:02 INFO - mozprofile == 0.28 00:05:02 INFO - mozrunner == 6.12 00:05:02 INFO - mozscreenshot == 0.1 00:05:02 INFO - mozsystemmonitor == 0.3 00:05:02 INFO - moztest == 0.7 00:05:02 INFO - mozversion == 1.4 00:05:02 INFO - psutil == 3.1.1 00:05:02 INFO - requests == 1.2.3 00:05:02 INFO - wsgiref == 0.1.2 00:05:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:05:02 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')]} 00:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:05:02 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')]} 00:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:05:02 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:05:02 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 00:05:02 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 00:05:02 INFO - Using env: {'DISPLAY': ':0', 00:05:02 INFO - 'HOME': '/home/cltbld', 00:05:02 INFO - 'LANG': 'en_US.UTF-8', 00:05:02 INFO - 'LANGUAGE': 'en_US:en', 00:05:02 INFO - 'LOGNAME': 'cltbld', 00:05:02 INFO - 'MAIL': '/var/mail/cltbld', 00:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:05:02 INFO - 'MOZ_NO_REMOTE': '1', 00:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:05:02 INFO - 'NO_EM_RESTART': '1', 00:05:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:05:02 INFO - 'PWD': '/builds/slave/test', 00:05:02 INFO - 'SHELL': '/bin/bash', 00:05:02 INFO - 'SHLVL': '1', 00:05:02 INFO - 'TERM': 'linux', 00:05:02 INFO - 'TMOUT': '86400', 00:05:02 INFO - 'USER': 'cltbld', 00:05:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:05:02 INFO - '_': '/tools/buildbot/bin/python'} 00:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 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)) 00:05:02 INFO - Cleaning up... 00:05:02 INFO - Return code: 0 00:05:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:05:02 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')]} 00:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:05:02 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')]} 00:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:05:02 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 0x7fabeca1bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x118eca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, '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': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', '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 00:05:02 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 00:05:02 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 00:05:02 INFO - Using env: {'DISPLAY': ':0', 00:05:02 INFO - 'HOME': '/home/cltbld', 00:05:02 INFO - 'LANG': 'en_US.UTF-8', 00:05:02 INFO - 'LANGUAGE': 'en_US:en', 00:05:02 INFO - 'LOGNAME': 'cltbld', 00:05:02 INFO - 'MAIL': '/var/mail/cltbld', 00:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:05:02 INFO - 'MOZ_NO_REMOTE': '1', 00:05:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:05:02 INFO - 'NO_EM_RESTART': '1', 00:05:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:05:02 INFO - 'PWD': '/builds/slave/test', 00:05:02 INFO - 'SHELL': '/bin/bash', 00:05:02 INFO - 'SHLVL': '1', 00:05:02 INFO - 'TERM': 'linux', 00:05:02 INFO - 'TMOUT': '86400', 00:05:02 INFO - 'USER': 'cltbld', 00:05:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:05:02 INFO - '_': '/tools/buildbot/bin/python'} 00:05:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:05:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:05:02 INFO - Cleaning up... 00:05:02 INFO - Return code: 0 00:05:02 INFO - Running post-action listener: _resource_record_post_action 00:05:02 INFO - Running post-action listener: _start_resource_monitoring 00:05:02 INFO - Starting resource monitoring. 00:05:02 INFO - [mozharness: 2016-09-21 07:05:02.785512Z] Finished create-virtualenv step (success) 00:05:02 INFO - [mozharness: 2016-09-21 07:05:02.785961Z] Running install step. 00:05:02 INFO - Running pre-action listener: _resource_record_pre_action 00:05:02 INFO - Running main action method: install 00:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:05:02 INFO - Reading from file tmpfile_stdout 00:05:02 INFO - Detecting whether we're running mozinstall >=1.0... 00:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:05:03 INFO - Reading from file tmpfile_stdout 00:05:03 INFO - Output received: 00:05:03 INFO - Usage: mozinstall [options] installer 00:05:03 INFO - Options: 00:05:03 INFO - -h, --help show this help message and exit 00:05:03 INFO - -d DEST, --destination=DEST 00:05:03 INFO - Directory to install application into. [default: 00:05:03 INFO - "/builds/slave/test"] 00:05:03 INFO - --app=APP Application being installed. [default: firefox] 00:05:03 INFO - mkdir: /builds/slave/test/build/application 00:05:03 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'] 00:05:03 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 00:05:19 INFO - Reading from file tmpfile_stdout 00:05:19 INFO - Output received: 00:05:19 INFO - /builds/slave/test/build/application/firefox/firefox 00:05:19 INFO - Running post-action listener: _resource_record_post_action 00:05:19 INFO - [mozharness: 2016-09-21 07:05:19.112279Z] Finished install step (success) 00:05:19 INFO - [mozharness: 2016-09-21 07:05:19.112526Z] Running run-tests step. 00:05:19 INFO - Running pre-action listener: _resource_record_pre_action 00:05:19 INFO - Running main action method: run_tests 00:05:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:05:19 INFO - Python 2.7.3 00:05:19 INFO - Return code: 0 00:05:19 INFO - grabbing minidump binary from tooltool 00:05:19 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')]} 00:05:19 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 0x118bf60>, '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 0x118cd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x118d1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:05:19 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 00:05:19 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 00:05:19 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 00:05:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:05:19 INFO - Return code: 0 00:05:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:05:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:05:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:05:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:05:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:05:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 00:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 00:05:19 INFO - Using env: {'DISPLAY': ':0', 00:05:19 INFO - 'HOME': '/home/cltbld', 00:05:19 INFO - 'LANG': 'en_US.UTF-8', 00:05:19 INFO - 'LANGUAGE': 'en_US:en', 00:05:19 INFO - 'LOGNAME': 'cltbld', 00:05:19 INFO - 'MAIL': '/var/mail/cltbld', 00:05:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:05:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:05:19 INFO - 'MOZ_NO_REMOTE': '1', 00:05:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:05:19 INFO - 'NO_EM_RESTART': '1', 00:05:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:05:19 INFO - 'PWD': '/builds/slave/test', 00:05:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:05:19 INFO - 'SHELL': '/bin/bash', 00:05:19 INFO - 'SHLVL': '1', 00:05:19 INFO - 'TERM': 'linux', 00:05:19 INFO - 'TMOUT': '86400', 00:05:19 INFO - 'USER': 'cltbld', 00:05:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220', 00:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:05:19 INFO - '_': '/tools/buildbot/bin/python'} 00:05:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 00:05:19 INFO - mozversion application_buildid: 20160920233206 00:05:19 INFO - mozversion application_changeset: 36373fbb78111d4fe0af105056d5066d1a669739 00:05:19 INFO - mozversion application_display_name: Nightly 00:05:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:05:19 INFO - mozversion application_name: Firefox 00:05:19 INFO - mozversion application_remotingname: firefox 00:05:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:05:19 INFO - mozversion application_vendor: Mozilla 00:05:19 INFO - mozversion application_version: 52.0a1 00:05:19 INFO - mozversion platform_buildid: 20160920233206 00:05:19 INFO - mozversion platform_changeset: 36373fbb78111d4fe0af105056d5066d1a669739 00:05:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:05:19 INFO - mozversion platform_version: 52.0a1 00:05:19 INFO - using testdate: 1474441519 00:05:19 INFO - actual date: 1474441519 00:05:19 INFO - starting webserver on 'localhost:39599' 00:05:19 INFO - SUITE-START | Running 2 tests 00:05:19 INFO - TEST-START | dromaeo_css 00:05:19 INFO - operating with platform_type : linux_ 00:05:19 INFO - Initialising browser for dromaeo_css test... 00:05:19 INFO - TEST-INFO | started process 25200 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpssH3fJ/profile http://localhost:39599/getInfo.html) 00:05:23 INFO - PROCESS | 25200 | __metrics Screen width/height:1600/1200 00:05:23 INFO - PROCESS | 25200 | colorDepth:24 00:05:23 INFO - PROCESS | 25200 | Browser inner width/height: 1024/697 00:05:23 INFO - PROCESS | 25200 | __metrics 00:05:24 INFO - PROCESS | 25200 | 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" 00:05:24 INFO - PROCESS | 25200 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:05:25 INFO - TEST-INFO | 25200: exit 0 00:05:25 INFO - Browser initialized. 00:05:25 INFO - Running cycle 1/1 for dromaeo_css test... 00:05:25 INFO - TEST-INFO | started process 25279 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpssH3fJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:05:37 INFO - PROCESS | 25279 | RSS: Main: 175099904 00:05:37 INFO - PROCESS | 25279 | 00:05:41 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.15006 seconds. 00:06:15 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 00:06:20 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 00:06:22 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 00:06:29 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:06:29 INFO - PROCESS | 25279 | 1474441589223 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] 00:06:41 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:06:48 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 00:07:00 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:07:12 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 00:07:16 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:07:20 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 00:07:39 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.18338 seconds. 00:07:55 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:07:57 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:07:59 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 00:08:04 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 00:08:17 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 00:08:21 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 00:08:32 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:08:41 INFO - PROCESS | 25279 | Cycle 1(1): loaded http://localhost:39599/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39599/tests/dromaeo/cssquery-ext.html) 00:08:42 INFO - PROCESS | 25279 | RSS: Main: 293126144 00:08:42 INFO - PROCESS | 25279 | 00:08:52 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 00:08:59 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:09:03 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:09:05 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:09:13 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:09:23 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:09:38 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 00:09:41 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:09:56 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:10:08 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:10:10 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:10:28 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:10:34 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 00:10:42 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:10:46 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:10:50 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:11:25 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:11:29 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:11:31 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 00:11:33 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:11:37 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:11:41 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:11:45 INFO - PROCESS | 25279 | Cycle 1(1): loaded http://localhost:39599/tests/dromaeo/cssquery-ext.html (next: http://localhost:39599/tests/dromaeo/cssquery-jquery.html) 00:11:45 INFO - PROCESS | 25279 | RSS: Main: 276709376 00:11:45 INFO - PROCESS | 25279 | 00:11:49 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 00:11:53 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:12:08 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:12:15 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:12:16 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:12:18 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:12:28 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:12:44 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:12:50 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:12:52 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:13:05 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:13:09 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:13:11 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:13:13 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:13:14 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 2.01673 seconds. 00:13:17 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 00:13:19 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:13:23 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01665 seconds. 00:13:41 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:13:43 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:13:45 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:13:47 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:13:49 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:13:53 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 00:13:55 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:14:07 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:14:11 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:14:13 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:14:20 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:14:21 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:14:30 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:14:48 INFO - PROCESS | 25279 | Cycle 1(1): loaded http://localhost:39599/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39599/tests/dromaeo/cssquery-mootools.html) 00:14:48 INFO - PROCESS | 25279 | RSS: Main: 219455488 00:14:48 INFO - PROCESS | 25279 | 00:15:03 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:15:15 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:15:31 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:15:33 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:15:51 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:16:01 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 00:16:03 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:16:05 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:16:14 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:16:16 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:16:32 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:16:34 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:16:50 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:17:00 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:17:02 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:17:06 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:17:12 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:17:32 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:17:48 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:17:50 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:17:50 INFO - PROCESS | 25279 | Cycle 1(1): loaded http://localhost:39599/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39599/tests/dromaeo/cssquery-prototype.html) 00:17:51 INFO - PROCESS | 25279 | RSS: Main: 241004544 00:17:51 INFO - PROCESS | 25279 | 00:18:08 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 00:18:19 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:18:20 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:18:38 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 00:18:52 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 00:18:54 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:19:12 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:19:13 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:19:17 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:19:26 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 00:19:31 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:19:53 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:19:56 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:19:59 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:20:01 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:20:14 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:20:16 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:20:26 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:20:32 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:20:34 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:20:36 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:20:44 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 00:20:50 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:20:52 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:20:54 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:20:54 INFO - PROCESS | 25279 | Cycle 1(1): loaded http://localhost:39599/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39599/tests/dromaeo/cssquery-yui.html) 00:20:55 INFO - PROCESS | 25279 | RSS: Main: 222367744 00:20:55 INFO - PROCESS | 25279 | 00:20:58 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 00:21:10 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:21:13 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:21:15 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 00:21:19 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:21:20 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:21:22 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:21:28 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:21:30 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:21:52 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:21:54 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:22:12 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:22:14 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:22:25 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:22:30 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 00:22:35 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:22:43 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:22:47 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:22:57 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:23:01 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:23:05 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:23:21 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:23:25 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:23:38 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:23:46 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:23:50 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:23:54 INFO - PROCESS | 25279 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 00:23:58 INFO - PROCESS | 25279 | Cycle 1(1): loaded http://localhost:39599/tests/dromaeo/cssquery-yui.html (next: http://localhost:39599/tests/dromaeo/cssquery-dojo.html) 00:23:58 INFO - PROCESS | 25279 | RSS: Main: 315355136 00:23:58 INFO - PROCESS | 25279 | 00:23:58 INFO - PROCESS | 25279 | __start_tp_report 00:23:58 INFO - PROCESS | 25279 | _x_x_mozilla_page_load 00:23:58 INFO - PROCESS | 25279 | _x_x_mozilla_page_load_details 00:23:58 INFO - PROCESS | 25279 | |i|pagename|runs| 00:23:58 INFO - PROCESS | 25279 | |0;dojo.html;2555.425029368392;2582.6900771907376;2607.9478410431793;2608.9999999999995;2626.3565426470504;4601.9539804601945;4662.836800711976;4697.718136911783;4741.976290118549;4749.38258026457;4623.728474669468;4641.118187544373;4694.718316900989;4700.835470758524;4717.080169366975;4205.1169254456445;4243.893902652443;4263.232618128735;4281.100968796556;4339.804708788096;4318.784060796963;4323.783810809463;4359.367891655724;4363.476382834065;4420.403245561856;4178.623923846869;4203.495580530325;4338.479382474124;4375.618444488413;4397.208502469554;4230.17511585241;4239.554846741109;4283.20760659279;4283.892902677458;4358.978205108954;4270.252705776476;4271.97864010681;4275;4275.508316543606;4329.653627709808;4650.441946966385;4676.205045142334;4682.578567928868;4686.695364801277;4710.858674239778;4903.043906438246;4906.681065730716;4948.90102197954;4964.90070198594;4973.5772459341;5046.318746969149;5069.569086627641;5071.112555302807;5071.239314102877;5130.794768209267;4716.457607375127;4717.056588682277;4720.409948756405;4772.785224664898;4780.856574302777;5044.9495505045215;5050.772715227824;5132.948670513247;5168.483151684876;5177.637565370388;4984.40187177541;4985.202367621163;4985.302057711923;5033.949660503421;5048.671836330626;5059;5059.367579052619;5063.8480845574695;5064.392272927199;5134.897302053938;4870.342503762017;4905.068037072945;4991.301217829507;5017.222330538772;5028.371453568304;5011.6241281904;5039.949600504021;5040.092783299041;5104.081265372269;5107.080725469451;4327.242732521796;4338.457692788402;4355.7822108894425;4360.825566977285;4409.779511024436;3707.2400157967554;3731.6828069614385;3775.3770627846097;3812.5615554211067;3829.5021647185686;4316.352547117957;4322.848700295474;4335.7615331157085;4356.390105385249;4397.868063958086;5026.2460630904925;5029.5221953914315;5030.365022656429;5038.546530812171;5100.821471248489;4321.524632290446;4331.393604895317;4332.6317263033;4333.133373325284;4392.560743925582;4401.977990110029;4454.064646424223;4464.888377790581;4476.440444944382;4483.865484035484;4683.437987441529;4739.075880203396;4745.691530050535;4746.644001699955;4748.667593268577;4908.803647854046;4947.084789313988;4949.876253093701;4957.975210123926;5012.172991456368;4626.676132670681;4668.136394766979;4681.929771053505;4686.203345431353;4741.976290118528;4835.854924352275;4847.587955023862;4852.466228714909;4877.756112194305;4947.529984651453;4950.306957025947;4960.379952505937;4963.975180124076;4964.751762411937;5026.271190677258;4811.735354555533;4823.204171311694;4838.298446725134;4863.570609068802;4897.95102048975;4828.758562071812;4829;4829.2997515359375;4837;4885.804567817389;4780.378550788375;4786.425628924551;4806.423229212517;4834.709917404967;4866.343043689057;4858.392700912386;4885.951140488692;4900.362952816111;4901.142300097397;4980.228064649985;4713.340132381538;4765.166095933268;4791.113643975876;4812.326274321527;4826.8793280168275 00:23:58 INFO - PROCESS | 25279 | |1;ext.html;566.9879265511063;569.5016860247283;578.6730497269043;580.8431723434671;587.2453896742686;30004;38663.22673546527;40363.19273614526;40372.596274037285;44253.55746442534;39515;39891.800540997254;40014.7999260004;40118.59881401185;40193;7517.36102431294;7657.349125324354;7672.961635191817;7676.46264761467;7678.42411819113;7682.116556595979;7772.65023073963;7775.766726998199;7777.961110194441;7780.922190778105;7779.8833017504785;7800.414968877329;7801.843963120734;7811.726589569366;7813.843723125534;7778.727744528915;7811.492253003594;7814.843703125905;7820.569868657221;7824.921750782533;7663.731769388045;7740.26467485588;7754;7755;7755.108162561265;37385.439218411746;37706.24587508262;37737.81131094354;37746.24507509834;37766.622333776584;2701.5677491601446;2724.6185534025253;2765.308672831792;2857.2428306498587;2870.8708108135183;27230.45539089207;28227.29431764202;28252.947909845596;28345.14964551046;28594.714052859414;3141.4188375150443;3154.1799132225556;3172.460681684119;3280.1471617379416;3447.637998010198;238284;239808;240720;240729;240937;54278.18582721263;54732.452675472734;54768.72615636896;54803.451965480635;54842;32259.51610725841;32962;33106.50340244898;35073.42169602374;56727.71636141793;12716.427760750788;12732.554360597334;12809.871901281054;12869.034822388172;12883.162594431333;9994.650187243411;10096;10097.141742951784;10099.04059114383;10107.241956853119;17891.373801917132;17966.461006169837;17997.7300340495;18000.459986200436;18021.369252076376;6597.909296236364;6631.734730610818;6725.327467253235;6841.863162736718;6850.725970961105;766.2490759056157;777.1684297801411;840.4537050916953;862.9266512346395;937.1752857485369;26716.866415668184;28640.82492251452;28690;28698.856505717336;28952.276193095342;880.2473884828507;886.0607755778894;900.8918929728344;952.6951375559893;958.166395236149;14784.926075369554;14840.332185051699;14890.776638350433;14988;15031.473898413504;346808;350091;350559;351362;352445;48375.5162448378;48855.51144488581;48973.75513122412;49009.75495122502;49019.26471102936;1062.4156713807374;1086.4567716141928;1127.323605836508;1133.2350663302072;1185.318441895896;27803;28949.13152605425;29237.444885471694;29512.262193445335;29910.25224369408;41145;41527.58472415358;41549.37675934924;41714.374284386366;41724.3741343874;281739;282323;282418;282968;283236;22318.442038949157;22991;24203.273901782974;24592;24665.01339946382;40797.79601101975;40971.38542921799;41056;41069;41284.79357603193;34873.65126348704;35052.29895402027;35066.47400288947;35589.28821423609;35751.28497430089;35517;35582.46626300556;35599.82200088983;35648.821755891055;35991.64008359883;34719;35268.823655881555;35351.29297413986;36561.634383656885;38654.80672596619;30588.694113058584;33290.66709332875;33557.66442335643;33677.65289388397;33695.66304337023;25061.74938250667;25542;25591.87204063968;25711.10011149601;25877.353066173495 00:23:58 INFO - PROCESS | 25279 | |2;jquery.html;335.35611625678695;348.372928728289;372.3260897775027;373.31869338457307;381.18236382958554;15356.616084597877;16418;17416.477505674833;17511.73732394015;17544.912275438637;16194.838051619481;16768.83231167688;16922.40771572995;17033.403830865893;17069;4910.779014944318;5198.350206224222;5203.349581302337;5204.3234379530795;5206.505381988705;3633.218857945554;3809.4857194278834;5124.0776660201145;5832.941670583282;6124.8468788280425;6200.534959878028;6214.694914068051;6217.347178546255;6218.22272215973;6221.59559628625;5910.556708246877;6015.969920150415;6031.547633927473;6033.396660333975;6042.1238920356745;5250.159974404115;5271.314729085233;5328.92006619901;5330.1205301125265;5335.919961200585;20412.081456334352;20721.792782072138;20750.481237969172;20807.1677132916;20845.8957705212;1815.2920361059198;1907.9332223372255;1969.8522610804248;2126.553423781008;2314.00497785952;13029.86970130296;13202.273874936875;13235.801462978063;13540.796888046687;13675.589732308046;1706.8719846011475;1708.4020592792547;1735.1237624999294;1763.938262160818;1805.1966874740745;65133.674331628514;65244.673776630814;65864.34135658678;65993;66366.33633663699;9498.477583732823;9614.95192524033;9860.408375497349;9890.357126786736;9924.60301587943;13217.801732974012;13541.864581354257;13559.525416610371;13569;13580.25308608036;7243.5653860768525;7418.109826820708;7444.181140074587;7457.030586023782;7634.732784352521;6895.482993741369;7008.194057683329;7185.568865868065;7228.927710722931;7235.710571577078;5978.282606087298;6033;6051.485623722033;6053.878922421527;6161.537884658579;1536.9001014933995;1628.7964004499438;1684.8315168483055;1687.3419366447094;1780.97328540072;780.1535334162384;798.7763426240662;819.971301004462;846.3018010141535;883.5582208895552;16527.33890644361;16835.663286734198;17701;17705.7344139838;18071.367502137364;836.958152092393;842.8230071684892;850.5534594337898;880.4805164952709;884.526778173674;9012.41419307743;9708.514574271258;10001.649942251986;10135.442550659636;10288.485575721184;396118;397795;398625;401119;424160;47250.763746181736;75255;76642;76730;78288.60855695799;899.869518919753;907.9001309855911;940.3934462271797;946.4936259101348;951.5575257505293;11654.475548600503;15627.453039143575;16318.673626527641;16432.260548275117;16683;28179;36202.4569631461;36722.4491632631;36926.63073369232;38580.614193858826;45077.54922450865;45903.54096458993;48796;48943.26585101298;51298.48701512937;7283.781486555412;7714.884276735741;7760.805979850323;7922;8058.274755272054;8495.405321627426;10850.348979061282;10999.505022273857;11005.779884402427;12222.144449888423;8722.476651400937;9335.299852511225;9594.136527712539;9684.225261978883;9972.900270997197;9748.853767193355;10016.749581260236;10309.639162629272;10514.474276286002;10701.143908487458;8997.190252877272;9445.811083778148;9711.320207585684;9842.458664773507;9959.900400995897;7896.131425543301;7945.443818932619;7977.840443190988;8001.479903806234;8062.072861620989;8531.360147988802;9194.678186263449;9284.35009549352;9463.905360946303;9592.184664303611 00:23:58 INFO - PROCESS | 25279 | |3;mootools.html;1357.3281225793235;1377.1117629129212;1433.663089174044;1632.5918520369908;1686.4771920704582;6829.590224586515;7018.298170182992;7344.26557344266;7962.402819788517;7966.203379662031;7334.339909408152;7593.1647518773025;7598.3541398981015;7732.226777322265;7751.224877512246;2154.095279982403;2183.4759657682202;2190.9014094365793;2194.067521303448;2194.462356722597;1812.9909350453229;1876.5121068522146;2094.6753253245697;2101.674240492726;2120.7455105387376;2134.157007981846;2171.6091103601348;2176.6952626632283;2184.8907554622206;2185.8797766122857;2157.11558261113;2170.4248374180856;2173.228503881115;2182.6725991101384;2182.923597674074;2116.5661039486868;2132.168454302823;2134.8505604607685;2136.59404713104;2139.122959586562;7966.681332746683;8027.157148499382;8035.477693949874;8036.718714845011;8038.517688938682;4417.668674849367;4554.567316104964;4561.40701708779;4599.05719327537;4606.262997920317;2812.7609153222;2922.655578433923;2949.0268211490156;2949.0710426215887;2973.6431628204537;875.9474431534128;898.9865152022725;914.4147745442922;930.8184903943734;932.5710173320281;10739.570417183304;10759.3544387337;10763.085137763299;10780.407077610807;10790.460476976199;3778.3765678663262;4083.224187404384;4246.171996460661;4250.723702959298;4271.572842715704;9360.765980850534;9366.110219529133;9374.390664606779;9378.624855005859;9381.061893810564;4514.0971805639;4516.638668906546;4521.434820647419;4523.864284071483;4528.298113792367;4088.8942194769716;4119.340905455173;4125.051238215227;4133.194027164674;4139.79301034947;3598.298331825269;3679.5032670589126;3684.5946945835935;3686.760360576554;3697.6856967157555;1048.9318194317498;1098.5441041967495;1156.653004098767;1179.097990037622;1186.8813118688236;759.6733404635949;765.8991119146568;769.692123150733;794.408165916396;797.7726347990795;4898.90202195954;4995.925061124086;5013.172826483662;5058.064258112261;5263.894722105613;800.2837460472845;843.8317678424002;849.9685751054983;861.1818772166467;862.2972277593866;3359.5632567766033;3393.321335732814;3407.437772767515;3417.743669224818;3538.610752817188;9530.52347382628;10002.49987500622;10009.299349045643;10015.349002314972;10028.899711002943;9794.069563391466;9854.11312981835;9907.306488545733;9910.15763660097;9937.503124843728;6899.793006209822;7138.143422789299;7402.074740657418;7483.251674832473;7483.700651973969;5246.186841039645;5249.580033597226;5274.709890955881;5292.100342941786;5343.251944727819;8482.363822713198;8651.091635378194;8763.079876612865;8836.95581522114;8869.778255543662;8735.606897689491;8812.559372031501;8873;8895.866562001445;8915.643374264957;6792.422644075309;6898.827529311808;6909.343612356818;6912.827179320558;6919.965400172967;7389.593572353358;7433.331000210007;7527.6988920444755;7544.47188696786;7573.734919277799;5321.574274057989;6519.641419721961;6536.379043990813;6569.343065693392;6862.107925969592;6398.616083035033;6401.071844582602;6415.8396040097505;6416.582922110048;6431.067495213074;5701.372848986525;5811.360750317377;5811.912821307768;5817.70911454434;5832.212651292021;4143.19207754485;4179.9582004179565;4282.957170428256;4306.160298741841;4306.267934451088;4262.147570485853;4327.913441731211;4329.437173167468;4368.191884501377;4415.668824838214 00:23:58 INFO - PROCESS | 25279 | |4;prototype.html;286.8021065464829;287.07132426599946;296.42641488719323;297.6264787691447;297.91509419815344;4665.276882083274;4859.878503037417;4895.975520122399;4900;4946.307516947626;4655.255159174532;4811.831585894485;4812.879678008052;4818.831340903069;4829.637777166718;3816.790076545789;3820.923581528368;3823.0633494793815;3830.0616348994536;3839.731218814677;3605.7836529808164;3840.462335273064;3847.7883716395586;3853.614638536152;3860.7683538987612;3451.137215696076;3692.686121679647;3701.444783282516;3714.532759559972;3721.3673675475097;3686.8525258989607;3701.5002974598356;3710.3506886294927;3718.382503610938;3727.5340582427198;3229.6931791479774;3241.9027429177163;3250.902472925792;3252.2357246047395;3274.934501309984;4638.443386793572;4695.835645752416;4714.292856071583;4780.211265141247;4788.449328327252;4290.485141782975;4292.699511034229;4300.311950087971;4303.182395344875;4305.289627211507;5081.644284900059;5083.669561478529;5092.261622064817;5094.617903657204;5130.153524668425;3426.3661222673636;3562.9643703562892;3582.390993531106;3585.551806024247;3624.3294990426857;5245.816396426145;5269.789208431658;5270.393904700932;5277.366715994067;5295.7881684732565;3650.525431693916;3656.835442405045;3660.5973342932257;3666.450032495147;3668.119651283726;3588.766730162531;3687.1519550503535;3701.408394390438;3707.5921648618632;3715.108373990223;4198.412222288882;4222.704410691284;4235.131797981407;4241.618254357061;4252.851150209728;5044.798208071636;5060.2156665716875;5072.594192464594;5094.1849304111165;5095.694258344512;1213.7997230456876;1239.7458521003173;1282.2306616030307;1287.7167023255056;1298.9025823063307;842.4625059266863;865.6321063547967;871.9476831390136;873.7073080517971;874.9518776467355;4503.729776213438;4508.684392092522;4514.864554063383;4516.796744146522;4529.935465165683;5184.6299905062015;5195.402528709171;5206.71363075027;5207.661502002357;5222.97388513055;4352.9129417412105;4497.527759585262;4511.1654343946475;4515.458145022619;4536.661684802991;2761.406297646016;2763.7701222955593;2763.9496991143637;2779.901938734229;2783.6360183509933;4911.263310503453;4911.263310503453;4931.5315045070665;4932.4327702314695;4946.579540739005;5065.189569668835;5264.210368444764;5297.258383826267;5313.548348390353;5317.30874986257;4877.902441951142;4888.60891128716;4894.363732714724;4905.362302900671;4913.852584422473;4964.652474326763;5190.8961820762615;5194.662346947436;5217.295665085317;5231.712255825966;5043.4365346742625;5083.745812709423;5115.8233606271515;5120.359955005624;5145.253938179018;4805.8077676892535;4836.516348365276;4887.315775791321;4904.190808516555;4907.435644900882;3627.7460577760357;3641.435577485494;3641.890743277706;3642.7450078494253;3649.233660931234;4904.754762261801;5082.517160869711;5190.948090519047;5199.4540573239265;5204.921926171186;5044.571211447068;5049.621278404208;5050.141475949171;5053.469385714447;5059.3169922061525;4875.634327425528;5047.394312682502;5053;5060.468650791761;5064.518870707277;4799.424069111729;4891.554222888556;4939.53074457926;4941.579965702956;4986.576141028053;5000.2499625056535;5173.482651734947;5198.246254293182;5205;5205.973970130277;5046.662634401813;5059.241113832954;5060.342155519759;5065.797368105234;5092.8472145835685 00:23:58 INFO - PROCESS | 25279 | |5;yui.html;526.9894602107955;555.8415851482328;559.2366419836922;562.524666656675;616.1251023546565;11953.64139075827;13145.342732863355;13153.276569788657;13172.538961136373;13176.93411532941;11702.180847340689;11962.521499140024;12240.75518489632;12256.693582660439;12282.877171228285;609.5946195779824;742.4209116888821;764.1556080531017;766.4826242286463;767.2787579675115;626.4706323156925;748.7491690283762;768.8500742355243;770.3875419041875;773.6479901644766;819.713100414853;821.8685010398337;822.0341099208437;822.1983566023109;822.4489591973362;639.27761629359;817.7669364231195;817.9836403271931;818.7257268814955;819.3608984991716;483.4561118741416;571.072007987021;585.5959388022251;587.6415386614162;588.3116753398534;12374.876251237552;12407.93796031014;12430.627081187578;12430.875691243153;12437.502499899992;10962;11356.37309550353;11407.828882566768;11572.537098516133;12033.819492707617;10958.616448424267;11150.386728729916;11197.888021119767;11450.427478626036;11511.079113670887;4064.512258528966;4070.3894415837567;4075.1442197138635;4138.275801734714;4234.661227101855;66071.339286607;68748;69705.65147174231;69820.30179698189;70870.2912970874;9790.26573007013;9936.821626557781;10001.299909006446;10217.897821021843;10361.896381036244;16316.75524867128;17470.213840377215;17494.912525437292;17506.29974801019;17545.56136096608;5815.244018277597;5876.471117599351;5969.074793407029;6001.279846418458;6194.039923811816;3596.6583174598372;3642.7085833133287;3717.75834570752;3720.144366795598;3738.04795253524;2741.0954385052883;2856.5001124803075;3106.751459883204;3257.28339765256;3261;540.694507603203;541.6668748719588;544.3087279155451;561.9971900140555;577.6101131736145;449.2609657114094;509.88527581294653;512.7000704587783;527.082875796112;539.4497612435373;7053.788386348418;7086.39765619928;7131.607761573163;7247.23903990084;7392.704291828267;467.12647349456734;467.29438547793075;477.54633098555814;479.5564103204522;492.59853219625893;3303.4168474259563;3379;3384.272381438003;3468;3535.7701749386206;76206.6189669048;79804;79935.41259454467;90462;107990;79729.60135199403;80621;81766.18233817705;82213;82247.5887620558;70410.29589704037;70933.64533177404;73414.63292683609;73957;77162.22837771551;7590.203028681909;7621.070899597623;7691.538507689556;7957.124716281089;8191.713290034821;42471.3629295567;44098.77950610226;44394.11211775811;44524.55475445334;47079.52920470751;56368.15447768369;63718.681406592674;65578.34421655722;65852.34147658462;65855;10213.693589192337;10469.31949423285;10489.423081730272;10539.841902371318;10640.574377025141;11268.661940141808;11450.71373215643;12491;12529.060320475819;12538.686532836751;10190.847137292798;10308.12380947598;10319.122874555747;10500.527476263434;11232.775344493228;9767.560459779184;10377;10672.039516443558;10728.356298621795;10997.725056873642;9412.95293523528;9729.22166226686;9763.221028476253;9778.41329520231;9870.358426702242;9208.248307380765;9423.434593924387;9580.425174489554;9698.418094914328;9857.704268871945;9084.364094513585;9263.814723705353;9414.764630884009;9472.763180920532;9524.523773811143 00:23:58 INFO - PROCESS | 25279 | __end_tp_report 00:23:58 INFO - PROCESS | 25279 | __start_cc_report 00:23:58 INFO - PROCESS | 25279 | _x_x_mozilla_cycle_collect,970 00:23:58 INFO - PROCESS | 25279 | __end_cc_report 00:23:58 INFO - PROCESS | 25279 | __startTimestamp1474442638654__endTimestamp 00:23:58 INFO - PROCESS | 25279 | 00:23:58 INFO - PROCESS | 25279 | ------- Summary: start ------- 00:23:58 INFO - PROCESS | 25279 | Number of tests: 6 00:23:58 INFO - PROCESS | 25279 | 00:23:58 INFO - PROCESS | 25279 | [#0] dojo.html Cycles:165 Average:4631.35 Median:4748.67 stddev:482.35 (10.2%) stddev-sans-first:455.51 00:23:58 INFO - PROCESS | 25279 | Values: 2555.4 2582.7 2607.9 2609.0 2626.4 4602.0 4662.8 4697.7 4742.0 4749.4 4623.7 4641.1 4694.7 4700.8 4717.1 4205.1 4243.9 4263.2 4281.1 4339.8 4318.8 4323.8 4359.4 4363.5 4420.4 4178.6 4203.5 4338.5 4375.6 4397.2 4230.2 4239.6 4283.2 4283.9 4359.0 4270.3 4272.0 4275.0 4275.5 4329.7 4650.4 4676.2 4682.6 4686.7 4710.9 4903.0 4906.7 4948.9 4964.9 4973.6 5046.3 5069.6 5071.1 5071.2 5130.8 4716.5 4717.1 4720.4 4772.8 4780.9 5044.9 5050.8 5132.9 5168.5 5177.6 4984.4 4985.2 4985.3 5033.9 5048.7 5059.0 5059.4 5063.8 5064.4 5134.9 4870.3 4905.1 4991.3 5017.2 5028.4 5011.6 5039.9 5040.1 5104.1 5107.1 4327.2 4338.5 4355.8 4360.8 4409.8 3707.2 3731.7 3775.4 3812.6 3829.5 4316.4 4322.8 4335.8 4356.4 4397.9 5026.2 5029.5 5030.4 5038.5 5100.8 4321.5 4331.4 4332.6 4333.1 4392.6 4402.0 4454.1 4464.9 4476.4 4483.9 4683.4 4739.1 4745.7 4746.6 4748.7 4908.8 4947.1 4949.9 4958.0 5012.2 4626.7 4668.1 4681.9 4686.2 4742.0 4835.9 4847.6 4852.5 4877.8 4947.5 4950.3 4960.4 4964.0 4964.8 5026.3 4811.7 4823.2 4838.3 4863.6 4898.0 4828.8 4829.0 4829.3 4837.0 4885.8 4780.4 4786.4 4806.4 4834.7 4866.3 4858.4 4886.0 4900.4 4901.1 4980.2 4713.3 4765.2 4791.1 4812.3 4826.9 00:23:58 INFO - PROCESS | 25279 | 00:23:58 INFO - PROCESS | 25279 | [#1] ext.html Cycles:180 Average:44414.89 Median:35549.73 stddev:77268.87 (217.4%) stddev-sans-first:77415.50 00:23:58 INFO - PROCESS | 25279 | Values: 567.0 569.5 578.7 580.8 587.2 30004.0 38663.2 40363.2 40372.6 44253.6 39515.0 39891.8 40014.8 40118.6 40193.0 7517.4 7657.3 7673.0 7676.5 7678.4 7682.1 7772.7 7775.8 7778.0 7780.9 7779.9 7800.4 7801.8 7811.7 7813.8 7778.7 7811.5 7814.8 7820.6 7824.9 7663.7 7740.3 7754.0 7755.0 7755.1 37385.4 37706.2 37737.8 37746.2 37766.6 2701.6 2724.6 2765.3 2857.2 2870.9 27230.5 28227.3 28252.9 28345.1 28594.7 3141.4 3154.2 3172.5 3280.1 3447.6 238284.0 239808.0 240720.0 240729.0 240937.0 54278.2 54732.5 54768.7 54803.5 54842.0 32259.5 32962.0 33106.5 35073.4 56727.7 12716.4 12732.6 12809.9 12869.0 12883.2 9994.7 10096.0 10097.1 10099.0 10107.2 17891.4 17966.5 17997.7 18000.5 18021.4 6597.9 6631.7 6725.3 6841.9 6850.7 766.2 777.2 840.5 862.9 937.2 26716.9 28640.8 28690.0 28698.9 28952.3 880.2 886.1 900.9 952.7 958.2 14784.9 14840.3 14890.8 14988.0 15031.5 346808.0 350091.0 350559.0 351362.0 352445.0 48375.5 48855.5 48973.8 49009.8 49019.3 1062.4 1086.5 1127.3 1133.2 1185.3 27803.0 28949.1 29237.4 29512.3 29910.3 41145.0 41527.6 41549.4 41714.4 41724.4 281739.0 282323.0 282418.0 282968.0 283236.0 22318.4 22991.0 24203.3 24592.0 24665.0 40797.8 40971.4 41056.0 41069.0 41284.8 34873.7 35052.3 35066.5 35589.3 35751.3 35517.0 35582.5 35599.8 35648.8 35991.6 34719.0 35268.8 35351.3 36561.6 38654.8 30588.7 33290.7 33557.7 33677.7 33695.7 25061.7 25542.0 25591.9 25711.1 25877.4 00:23:58 INFO - PROCESS | 25279 | 00:23:58 INFO - PROCESS | 25279 | [#2] jquery.html Cycles:180 Average:24402.26 Median:5260.74 stddev:66347.80 (1261.2%) stddev-sans-first:66509.31 00:23:58 INFO - PROCESS | 25279 | Values: 335.4 348.4 372.3 373.3 381.2 15356.6 16418.0 17416.5 17511.7 17544.9 16194.8 16768.8 16922.4 17033.4 17069.0 4910.8 5198.4 5203.3 5204.3 5206.5 3633.2 3809.5 5124.1 5832.9 6124.8 6200.5 6214.7 6217.3 6218.2 6221.6 5910.6 6016.0 6031.5 6033.4 6042.1 5250.2 5271.3 5328.9 5330.1 5335.9 20412.1 20721.8 20750.5 20807.2 20845.9 1815.3 1907.9 1969.9 2126.6 2314.0 13029.9 13202.3 13235.8 13540.8 13675.6 1706.9 1708.4 1735.1 1763.9 1805.2 65133.7 65244.7 65864.3 65993.0 66366.3 9498.5 9615.0 9860.4 9890.4 9924.6 13217.8 13541.9 13559.5 13569.0 13580.3 7243.6 7418.1 7444.2 7457.0 7634.7 6895.5 7008.2 7185.6 7228.9 7235.7 5978.3 6033.0 6051.5 6053.9 6161.5 1536.9 1628.8 1684.8 1687.3 1781.0 780.2 798.8 820.0 846.3 883.6 16527.3 16835.7 17701.0 17705.7 18071.4 837.0 842.8 850.6 880.5 884.5 9012.4 9708.5 10001.6 10135.4 10288.5 396118.0 397795.0 398625.0 401119.0 424160.0 47250.8 75255.0 76642.0 76730.0 78288.6 899.9 907.9 940.4 946.5 951.6 11654.5 15627.5 16318.7 16432.3 16683.0 28179.0 36202.5 36722.4 36926.6 38580.6 45077.5 45903.5 48796.0 48943.3 51298.5 7283.8 7714.9 7760.8 7922.0 8058.3 8495.4 10850.3 10999.5 11005.8 12222.1 8722.5 9335.3 9594.1 9684.2 9972.9 9748.9 10016.7 10309.6 10514.5 10701.1 8997.2 9445.8 9711.3 9842.5 9959.9 7896.1 7945.4 7977.8 8001.5 8062.1 8531.4 9194.7 9284.4 9463.9 9592.2 00:23:58 INFO - PROCESS | 25279 | 00:23:58 INFO - PROCESS | 25279 | [#3] mootools.html Cycles:180 Average:5079.09 Median:5004.55 stddev:2907.39 (58.1%) stddev-sans-first:2902.10 00:23:58 INFO - PROCESS | 25279 | Values: 1357.3 1377.1 1433.7 1632.6 1686.5 6829.6 7018.3 7344.3 7962.4 7966.2 7334.3 7593.2 7598.4 7732.2 7751.2 2154.1 2183.5 2190.9 2194.1 2194.5 1813.0 1876.5 2094.7 2101.7 2120.7 2134.2 2171.6 2176.7 2184.9 2185.9 2157.1 2170.4 2173.2 2182.7 2182.9 2116.6 2132.2 2134.9 2136.6 2139.1 7966.7 8027.2 8035.5 8036.7 8038.5 4417.7 4554.6 4561.4 4599.1 4606.3 2812.8 2922.7 2949.0 2949.1 2973.6 875.9 899.0 914.4 930.8 932.6 10739.6 10759.4 10763.1 10780.4 10790.5 3778.4 4083.2 4246.2 4250.7 4271.6 9360.8 9366.1 9374.4 9378.6 9381.1 4514.1 4516.6 4521.4 4523.9 4528.3 4088.9 4119.3 4125.1 4133.2 4139.8 3598.3 3679.5 3684.6 3686.8 3697.7 1048.9 1098.5 1156.7 1179.1 1186.9 759.7 765.9 769.7 794.4 797.8 4898.9 4995.9 5013.2 5058.1 5263.9 800.3 843.8 850.0 861.2 862.3 3359.6 3393.3 3407.4 3417.7 3538.6 9530.5 10002.5 10009.3 10015.3 10028.9 9794.1 9854.1 9907.3 9910.2 9937.5 6899.8 7138.1 7402.1 7483.3 7483.7 5246.2 5249.6 5274.7 5292.1 5343.3 8482.4 8651.1 8763.1 8837.0 8869.8 8735.6 8812.6 8873.0 8895.9 8915.6 6792.4 6898.8 6909.3 6912.8 6920.0 7389.6 7433.3 7527.7 7544.5 7573.7 5321.6 6519.6 6536.4 6569.3 6862.1 6398.6 6401.1 6415.8 6416.6 6431.1 5701.4 5811.4 5811.9 5817.7 5832.2 4143.2 4180.0 4283.0 4306.2 4306.3 4262.1 4327.9 4329.4 4368.2 4415.7 00:23:58 INFO - PROCESS | 25279 | 00:23:58 INFO - PROCESS | 25279 | [#4] prototype.html Cycles:180 Average:4195.38 Median:4815.86 stddev:1226.26 (25.5%) stddev-sans-first:1194.09 00:23:58 INFO - PROCESS | 25279 | Values: 286.8 287.1 296.4 297.6 297.9 4665.3 4859.9 4896.0 4900.0 4946.3 4655.3 4811.8 4812.9 4818.8 4829.6 3816.8 3820.9 3823.1 3830.1 3839.7 3605.8 3840.5 3847.8 3853.6 3860.8 3451.1 3692.7 3701.4 3714.5 3721.4 3686.9 3701.5 3710.4 3718.4 3727.5 3229.7 3241.9 3250.9 3252.2 3274.9 4638.4 4695.8 4714.3 4780.2 4788.4 4290.5 4292.7 4300.3 4303.2 4305.3 5081.6 5083.7 5092.3 5094.6 5130.2 3426.4 3563.0 3582.4 3585.6 3624.3 5245.8 5269.8 5270.4 5277.4 5295.8 3650.5 3656.8 3660.6 3666.5 3668.1 3588.8 3687.2 3701.4 3707.6 3715.1 4198.4 4222.7 4235.1 4241.6 4252.9 5044.8 5060.2 5072.6 5094.2 5095.7 1213.8 1239.7 1282.2 1287.7 1298.9 842.5 865.6 871.9 873.7 875.0 4503.7 4508.7 4514.9 4516.8 4529.9 5184.6 5195.4 5206.7 5207.7 5223.0 4352.9 4497.5 4511.2 4515.5 4536.7 2761.4 2763.8 2763.9 2779.9 2783.6 4911.3 4911.3 4931.5 4932.4 4946.6 5065.2 5264.2 5297.3 5313.5 5317.3 4877.9 4888.6 4894.4 4905.4 4913.9 4964.7 5190.9 5194.7 5217.3 5231.7 5043.4 5083.7 5115.8 5120.4 5145.3 4805.8 4836.5 4887.3 4904.2 4907.4 3627.7 3641.4 3641.9 3642.7 3649.2 4904.8 5082.5 5190.9 5199.5 5204.9 5044.6 5049.6 5050.1 5053.5 5059.3 4875.6 5047.4 5053.0 5060.5 5064.5 4799.4 4891.6 4939.5 4941.6 4986.6 5000.2 5173.5 5198.2 5205.0 5206.0 5046.7 5059.2 5060.3 5065.8 5092.8 00:23:58 INFO - PROCESS | 25279 | 00:23:58 INFO - PROCESS | 25279 | [#5] yui.html Cycles:180 Average:17194.36 Median:541.18 stddev:24725.83 (4568.9%) stddev-sans-first:24763.52 00:23:58 INFO - PROCESS | 25279 | Values: 527.0 555.8 559.2 562.5 616.1 11953.6 13145.3 13153.3 13172.5 13176.9 11702.2 11962.5 12240.8 12256.7 12282.9 609.6 742.4 764.2 766.5 767.3 626.5 748.7 768.9 770.4 773.6 819.7 821.9 822.0 822.2 822.4 639.3 817.8 818.0 818.7 819.4 483.5 571.1 585.6 587.6 588.3 12374.9 12407.9 12430.6 12430.9 12437.5 10962.0 11356.4 11407.8 11572.5 12033.8 10958.6 11150.4 11197.9 11450.4 11511.1 4064.5 4070.4 4075.1 4138.3 4234.7 66071.3 68748.0 69705.7 69820.3 70870.3 9790.3 9936.8 10001.3 10217.9 10361.9 16316.8 17470.2 17494.9 17506.3 17545.6 5815.2 5876.5 5969.1 6001.3 6194.0 3596.7 3642.7 3717.8 3720.1 3738.0 2741.1 2856.5 3106.8 3257.3 3261.0 540.7 541.7 544.3 562.0 577.6 449.3 509.9 512.7 527.1 539.4 7053.8 7086.4 7131.6 7247.2 7392.7 467.1 467.3 477.5 479.6 492.6 3303.4 3379.0 3384.3 3468.0 3535.8 76206.6 79804.0 79935.4 90462.0 107990.0 79729.6 80621.0 81766.2 82213.0 82247.6 70410.3 70933.6 73414.6 73957.0 77162.2 7590.2 7621.1 7691.5 7957.1 8191.7 42471.4 44098.8 44394.1 44524.6 47079.5 56368.2 63718.7 65578.3 65852.3 65855.0 10213.7 10469.3 10489.4 10539.8 10640.6 11268.7 11450.7 12491.0 12529.1 12538.7 10190.8 10308.1 10319.1 10500.5 11232.8 9767.6 10377.0 10672.0 10728.4 10997.7 9413.0 9729.2 9763.2 9778.4 9870.4 9208.2 9423.4 9580.4 9698.4 9857.7 9084.4 9263.8 9414.8 9472.8 9524.5 00:23:58 INFO - PROCESS | 25279 | -------- Summary: end -------- 00:23:58 INFO - PROCESS | 25279 | 00:23:59 INFO - TEST-INFO | 25279: exit 0 00:23:59 INFO - TEST-OK | dromaeo_css | took 1120322ms 00:23:59 INFO - TEST-START | kraken 00:23:59 INFO - operating with platform_type : linux_ 00:23:59 INFO - Initialising browser for kraken test... 00:23:59 INFO - TEST-INFO | started process 25579 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcw8xtH/profile http://localhost:39599/getInfo.html) 00:24:04 INFO - PROCESS | 25579 | __metrics Screen width/height:1600/1200 00:24:04 INFO - PROCESS | 25579 | colorDepth:24 00:24:04 INFO - PROCESS | 25579 | Browser inner width/height: 1024/697 00:24:04 INFO - PROCESS | 25579 | __metrics 00:24:04 INFO - PROCESS | 25579 | 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" 00:24:04 INFO - PROCESS | 25579 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:24:04 INFO - PROCESS | 25579 | console.error: PushService: 00:24:04 INFO - PROCESS | 25579 | stateChangeProcessEnqueue: Error transitioning state 00:24:04 INFO - PROCESS | 25579 | UnknownError 00:24:05 INFO - TEST-INFO | 25579: exit 0 00:24:05 INFO - Browser initialized. 00:24:05 INFO - Running cycle 1/1 for kraken test... 00:24:05 INFO - TEST-INFO | started process 25658 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcw8xtH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:24:17 INFO - PROCESS | 25658 | RSS: Main: 174546944 00:24:17 INFO - PROCESS | 25658 | 00:24:20 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39599/tests/kraken/driver.html?testName=audio-beat-detection) 00:24:21 INFO - PROCESS | 25658 | RSS: Main: 233709568 00:24:21 INFO - PROCESS | 25658 | 00:24:24 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39599/tests/kraken/driver.html?testName=audio-dft) 00:24:24 INFO - PROCESS | 25658 | RSS: Main: 218361856 00:24:24 INFO - PROCESS | 25658 | 00:24:28 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39599/tests/kraken/driver.html?testName=audio-fft) 00:24:28 INFO - PROCESS | 25658 | RSS: Main: 300560384 00:24:28 INFO - PROCESS | 25658 | 00:24:30 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39599/tests/kraken/driver.html?testName=audio-oscillator) 00:24:31 INFO - PROCESS | 25658 | RSS: Main: 209915904 00:24:31 INFO - PROCESS | 25658 | 00:24:33 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39599/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:24:34 INFO - PROCESS | 25658 | RSS: Main: 214020096 00:24:34 INFO - PROCESS | 25658 | 00:24:37 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39599/tests/kraken/driver.html?testName=imaging-darkroom) 00:24:38 INFO - PROCESS | 25658 | RSS: Main: 249921536 00:24:38 INFO - PROCESS | 25658 | 00:24:41 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39599/tests/kraken/driver.html?testName=imaging-desaturate) 00:24:42 INFO - PROCESS | 25658 | RSS: Main: 244064256 00:24:42 INFO - PROCESS | 25658 | 00:24:45 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39599/tests/kraken/driver.html?testName=json-parse-financial) 00:24:45 INFO - PROCESS | 25658 | RSS: Main: 247439360 00:24:45 INFO - PROCESS | 25658 | 00:24:47 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39599/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:24:48 INFO - PROCESS | 25658 | RSS: Main: 210907136 00:24:48 INFO - PROCESS | 25658 | 00:24:50 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39599/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:24:50 INFO - PROCESS | 25658 | RSS: Main: 217788416 00:24:50 INFO - PROCESS | 25658 | 00:24:52 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39599/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:24:53 INFO - PROCESS | 25658 | RSS: Main: 222687232 00:24:53 INFO - PROCESS | 25658 | 00:24:56 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39599/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:24:57 INFO - PROCESS | 25658 | RSS: Main: 224038912 00:24:57 INFO - PROCESS | 25658 | 00:25:00 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39599/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:25:00 INFO - PROCESS | 25658 | RSS: Main: 221249536 00:25:00 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | Cycle 1(1): loaded http://localhost:39599/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39599/tests/kraken/driver.html?testName=ai-astar) 00:25:02 INFO - PROCESS | 25658 | RSS: Main: 273244160 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | __start_tp_report 00:25:02 INFO - PROCESS | 25658 | _x_x_mozilla_page_load 00:25:02 INFO - PROCESS | 25658 | _x_x_mozilla_page_load_details 00:25:02 INFO - PROCESS | 25658 | |i|pagename|runs| 00:25:02 INFO - PROCESS | 25658 | |0;ai-astar;112;117;116;115;116;115;115;114;114;115 00:25:02 INFO - PROCESS | 25658 | |1;audio-beat-detection;155;123;125;173;132;123;122;130;168;127 00:25:02 INFO - PROCESS | 25658 | |2;audio-dft;163;160;160;163;161;161;162;160;161;159 00:25:02 INFO - PROCESS | 25658 | |3;audio-fft;76;77;76;115;77;78;77;76;98;77 00:25:02 INFO - PROCESS | 25658 | |4;audio-oscillator;84;83;128;83;85;85;118;83;83;122 00:25:02 INFO - PROCESS | 25658 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;116;117;117 00:25:02 INFO - PROCESS | 25658 | |6;imaging-darkroom;147;148;147;147;147;147;148;147;147;148 00:25:02 INFO - PROCESS | 25658 | |7;imaging-desaturate;96;95;96;96;95;96;96;96;95;96 00:25:02 INFO - PROCESS | 25658 | |8;json-parse-financial;64;62;62;62;64;64;64;65;62;64 00:25:02 INFO - PROCESS | 25658 | |9;json-stringify-tinderbox;67;68;68;67;69;68;70;68;67;67 00:25:02 INFO - PROCESS | 25658 | |10;stanford-crypto-aes;84;84;87;82;82;83;82;83;82;83 00:25:02 INFO - PROCESS | 25658 | |11;stanford-crypto-ccm;138;136;132;141;129;131;131;136;177;132 00:25:02 INFO - PROCESS | 25658 | |12;stanford-crypto-pbkdf2;171;175;173;172;173;173;180;170;172;171 00:25:02 INFO - PROCESS | 25658 | |13;stanford-crypto-sha256-iterative;71;69;70;69;70;69;69;69;68;69 00:25:02 INFO - PROCESS | 25658 | __end_tp_report 00:25:02 INFO - PROCESS | 25658 | __start_cc_report 00:25:02 INFO - PROCESS | 25658 | _x_x_mozilla_cycle_collect,1949 00:25:02 INFO - PROCESS | 25658 | __end_cc_report 00:25:02 INFO - PROCESS | 25658 | __startTimestamp1474442702729__endTimestamp 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | ------- Summary: start ------- 00:25:02 INFO - PROCESS | 25658 | Number of tests: 14 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:1.37 (1.2%) stddev-sans-first:0.97 00:25:02 INFO - PROCESS | 25658 | Values: 112.0 117.0 116.0 115.0 116.0 115.0 115.0 114.0 114.0 115.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#1] audio-beat-detection Cycles:10 Average:137.80 Median:131.00 stddev:19.75 (15.1%) stddev-sans-first:19.94 00:25:02 INFO - PROCESS | 25658 | Values: 155.0 123.0 125.0 173.0 132.0 123.0 122.0 130.0 168.0 127.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.33 (0.8%) stddev-sans-first:1.20 00:25:02 INFO - PROCESS | 25658 | Values: 163.0 160.0 160.0 163.0 161.0 161.0 162.0 160.0 161.0 159.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#3] audio-fft Cycles:10 Average:82.70 Median:77.00 stddev:13.18 (17.1%) stddev-sans-first:13.76 00:25:02 INFO - PROCESS | 25658 | Values: 76.0 77.0 76.0 115.0 77.0 78.0 77.0 76.0 98.0 77.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:83.00 stddev:18.98 (22.9%) stddev-sans-first:19.68 00:25:02 INFO - PROCESS | 25658 | Values: 84.0 83.0 128.0 83.0 85.0 85.0 118.0 83.0 83.0 122.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 00:25:02 INFO - PROCESS | 25658 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 00:25:02 INFO - PROCESS | 25658 | Values: 147.0 148.0 147.0 147.0 147.0 147.0 148.0 147.0 147.0 148.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 00:25:02 INFO - PROCESS | 25658 | Values: 96.0 95.0 96.0 96.0 95.0 96.0 96.0 96.0 95.0 96.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:64.00 stddev:1.16 (1.8%) stddev-sans-first:1.20 00:25:02 INFO - PROCESS | 25658 | Values: 64.0 62.0 62.0 62.0 64.0 64.0 64.0 65.0 62.0 64.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.00 00:25:02 INFO - PROCESS | 25658 | Values: 67.0 68.0 68.0 67.0 69.0 68.0 70.0 68.0 67.0 67.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.00 stddev:1.55 (1.9%) stddev-sans-first:1.62 00:25:02 INFO - PROCESS | 25658 | Values: 84.0 84.0 87.0 82.0 82.0 83.0 82.0 83.0 82.0 83.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#11] stanford-crypto-ccm Cycles:10 Average:138.30 Median:136.00 stddev:14.10 (10.4%) stddev-sans-first:14.95 00:25:02 INFO - PROCESS | 25658 | Values: 138.0 136.0 132.0 141.0 129.0 131.0 131.0 136.0 177.0 132.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:173.00 stddev:2.83 (1.6%) stddev-sans-first:2.91 00:25:02 INFO - PROCESS | 25658 | Values: 171.0 175.0 173.0 172.0 173.0 173.0 180.0 170.0 172.0 171.0 00:25:02 INFO - PROCESS | 25658 | 00:25:02 INFO - PROCESS | 25658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:69.00 stddev:0.82 (1.2%) stddev-sans-first:0.60 00:25:02 INFO - PROCESS | 25658 | Values: 71.0 69.0 70.0 69.0 70.0 69.0 69.0 69.0 68.0 69.0 00:25:02 INFO - PROCESS | 25658 | -------- Summary: end -------- 00:25:02 INFO - PROCESS | 25658 | 00:25:04 INFO - TEST-INFO | 25658: exit 0 00:25:04 INFO - TEST-OK | kraken | took 64381ms 00:25:04 INFO - SUITE-END | took 1184s 00:25:04 INFO - Completed test suite (00:19:44) 00:25:04 INFO - javascript benchmark 00:25:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2555.425029368392, 2582.6900771907376, 2607.9478410431793, 2608.9999999999995, 2626.3565426470504, 4601.9539804601945, 4662.836800711976, 4697.718136911783, 4741.976290118549, 4749.38258026457, 4623.728474669468, 4641.118187544373, 4694.718316900989, 4700.835470758524, 4717.080169366975, 4205.1169254456445, 4243.893902652443, 4263.232618128735, 4281.100968796556, 4339.804708788096, 4318.784060796963, 4323.783810809463, 4359.367891655724, 4363.476382834065, 4420.403245561856, 4178.623923846869, 4203.495580530325, 4338.479382474124, 4375.618444488413, 4397.208502469554, 4230.17511585241, 4239.554846741109, 4283.20760659279, 4283.892902677458, 4358.978205108954, 4270.252705776476, 4271.97864010681, 4275.0, 4275.508316543606, 4329.653627709808, 4650.441946966385, 4676.205045142334, 4682.578567928868, 4686.695364801277, 4710.858674239778, 4903.043906438246, 4906.681065730716, 4948.90102197954, 4964.90070198594, 4973.5772459341, 5046.318746969149, 5069.569086627641, 5071.112555302807, 5071.239314102877, 5130.794768209267, 4716.457607375127, 4717.056588682277, 4720.409948756405, 4772.785224664898, 4780.856574302777, 5044.9495505045215, 5050.772715227824, 5132.948670513247, 5168.483151684876, 5177.637565370388, 4984.40187177541, 4985.202367621163, 4985.302057711923, 5033.949660503421, 5048.671836330626, 5059.0, 5059.367579052619, 5063.8480845574695, 5064.392272927199, 5134.897302053938, 4870.342503762017, 4905.068037072945, 4991.301217829507, 5017.222330538772, 5028.371453568304, 5011.6241281904, 5039.949600504021, 5040.092783299041, 5104.081265372269, 5107.080725469451, 4327.242732521796, 4338.457692788402, 4355.7822108894425, 4360.825566977285, 4409.779511024436, 3707.2400157967554, 3731.6828069614385, 3775.3770627846097, 3812.5615554211067, 3829.5021647185686, 4316.352547117957, 4322.848700295474, 4335.7615331157085, 4356.390105385249, 4397.868063958086, 5026.2460630904925, 5029.5221953914315, 5030.365022656429, 5038.546530812171, 5100.821471248489, 4321.524632290446, 4331.393604895317, 4332.6317263033, 4333.133373325284, 4392.560743925582, 4401.977990110029, 4454.064646424223, 4464.888377790581, 4476.440444944382, 4483.865484035484, 4683.437987441529, 4739.075880203396, 4745.691530050535, 4746.644001699955, 4748.667593268577, 4908.803647854046, 4947.084789313988, 4949.876253093701, 4957.975210123926, 5012.172991456368, 4626.676132670681, 4668.136394766979, 4681.929771053505, 4686.203345431353, 4741.976290118528, 4835.854924352275, 4847.587955023862, 4852.466228714909, 4877.756112194305, 4947.529984651453, 4950.306957025947, 4960.379952505937, 4963.975180124076, 4964.751762411937, 5026.271190677258, 4811.735354555533, 4823.204171311694, 4838.298446725134, 4863.570609068802, 4897.95102048975, 4828.758562071812, 4829.0, 4829.2997515359375, 4837.0, 4885.804567817389, 4780.378550788375, 4786.425628924551, 4806.423229212517, 4834.709917404967, 4866.343043689057, 4858.392700912386, 4885.951140488692, 4900.362952816111, 4901.142300097397, 4980.228064649985, 4713.340132381538, 4765.166095933268, 4791.113643975876, 4812.326274321527, 4826.8793280168275], "value": 4600.482257119101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.9879265511063, 569.5016860247283, 578.6730497269043, 580.8431723434671, 587.2453896742686, 30004.0, 38663.22673546527, 40363.19273614526, 40372.596274037285, 44253.55746442534, 39515.0, 39891.800540997254, 40014.7999260004, 40118.59881401185, 40193.0, 7517.36102431294, 7657.349125324354, 7672.961635191817, 7676.46264761467, 7678.42411819113, 7682.116556595979, 7772.65023073963, 7775.766726998199, 7777.961110194441, 7780.922190778105, 7779.8833017504785, 7800.414968877329, 7801.843963120734, 7811.726589569366, 7813.843723125534, 7778.727744528915, 7811.492253003594, 7814.843703125905, 7820.569868657221, 7824.921750782533, 7663.731769388045, 7740.26467485588, 7754.0, 7755.0, 7755.108162561265, 37385.439218411746, 37706.24587508262, 37737.81131094354, 37746.24507509834, 37766.622333776584, 2701.5677491601446, 2724.6185534025253, 2765.308672831792, 2857.2428306498587, 2870.8708108135183, 27230.45539089207, 28227.29431764202, 28252.947909845596, 28345.14964551046, 28594.714052859414, 3141.4188375150443, 3154.1799132225556, 3172.460681684119, 3280.1471617379416, 3447.637998010198, 238284.0, 239808.0, 240720.0, 240729.0, 240937.0, 54278.18582721263, 54732.452675472734, 54768.72615636896, 54803.451965480635, 54842.0, 32259.51610725841, 32962.0, 33106.50340244898, 35073.42169602374, 56727.71636141793, 12716.427760750788, 12732.554360597334, 12809.871901281054, 12869.034822388172, 12883.162594431333, 9994.650187243411, 10096.0, 10097.141742951784, 10099.04059114383, 10107.241956853119, 17891.373801917132, 17966.461006169837, 17997.7300340495, 18000.459986200436, 18021.369252076376, 6597.909296236364, 6631.734730610818, 6725.327467253235, 6841.863162736718, 6850.725970961105, 766.2490759056157, 777.1684297801411, 840.4537050916953, 862.9266512346395, 937.1752857485369, 26716.866415668184, 28640.82492251452, 28690.0, 28698.856505717336, 28952.276193095342, 880.2473884828507, 886.0607755778894, 900.8918929728344, 952.6951375559893, 958.166395236149, 14784.926075369554, 14840.332185051699, 14890.776638350433, 14988.0, 15031.473898413504, 346808.0, 350091.0, 350559.0, 351362.0, 352445.0, 48375.5162448378, 48855.51144488581, 48973.75513122412, 49009.75495122502, 49019.26471102936, 1062.4156713807374, 1086.4567716141928, 1127.323605836508, 1133.2350663302072, 1185.318441895896, 27803.0, 28949.13152605425, 29237.444885471694, 29512.262193445335, 29910.25224369408, 41145.0, 41527.58472415358, 41549.37675934924, 41714.374284386366, 41724.3741343874, 281739.0, 282323.0, 282418.0, 282968.0, 283236.0, 22318.442038949157, 22991.0, 24203.273901782974, 24592.0, 24665.01339946382, 40797.79601101975, 40971.38542921799, 41056.0, 41069.0, 41284.79357603193, 34873.65126348704, 35052.29895402027, 35066.47400288947, 35589.28821423609, 35751.28497430089, 35517.0, 35582.46626300556, 35599.82200088983, 35648.821755891055, 35991.64008359883, 34719.0, 35268.823655881555, 35351.29297413986, 36561.634383656885, 38654.80672596619, 30588.694113058584, 33290.66709332875, 33557.66442335643, 33677.65289388397, 33695.66304337023, 25061.74938250667, 25542.0, 25591.87204063968, 25711.10011149601, 25877.353066173495], "value": 16860.784869744177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.35611625678695, 348.372928728289, 372.3260897775027, 373.31869338457307, 381.18236382958554, 15356.616084597877, 16418.0, 17416.477505674833, 17511.73732394015, 17544.912275438637, 16194.838051619481, 16768.83231167688, 16922.40771572995, 17033.403830865893, 17069.0, 4910.779014944318, 5198.350206224222, 5203.349581302337, 5204.3234379530795, 5206.505381988705, 3633.218857945554, 3809.4857194278834, 5124.0776660201145, 5832.941670583282, 6124.8468788280425, 6200.534959878028, 6214.694914068051, 6217.347178546255, 6218.22272215973, 6221.59559628625, 5910.556708246877, 6015.969920150415, 6031.547633927473, 6033.396660333975, 6042.1238920356745, 5250.159974404115, 5271.314729085233, 5328.92006619901, 5330.1205301125265, 5335.919961200585, 20412.081456334352, 20721.792782072138, 20750.481237969172, 20807.1677132916, 20845.8957705212, 1815.2920361059198, 1907.9332223372255, 1969.8522610804248, 2126.553423781008, 2314.00497785952, 13029.86970130296, 13202.273874936875, 13235.801462978063, 13540.796888046687, 13675.589732308046, 1706.8719846011475, 1708.4020592792547, 1735.1237624999294, 1763.938262160818, 1805.1966874740745, 65133.674331628514, 65244.673776630814, 65864.34135658678, 65993.0, 66366.33633663699, 9498.477583732823, 9614.95192524033, 9860.408375497349, 9890.357126786736, 9924.60301587943, 13217.801732974012, 13541.864581354257, 13559.525416610371, 13569.0, 13580.25308608036, 7243.5653860768525, 7418.109826820708, 7444.181140074587, 7457.030586023782, 7634.732784352521, 6895.482993741369, 7008.194057683329, 7185.568865868065, 7228.927710722931, 7235.710571577078, 5978.282606087298, 6033.0, 6051.485623722033, 6053.878922421527, 6161.537884658579, 1536.9001014933995, 1628.7964004499438, 1684.8315168483055, 1687.3419366447094, 1780.97328540072, 780.1535334162384, 798.7763426240662, 819.971301004462, 846.3018010141535, 883.5582208895552, 16527.33890644361, 16835.663286734198, 17701.0, 17705.7344139838, 18071.367502137364, 836.958152092393, 842.8230071684892, 850.5534594337898, 880.4805164952709, 884.526778173674, 9012.41419307743, 9708.514574271258, 10001.649942251986, 10135.442550659636, 10288.485575721184, 396118.0, 397795.0, 398625.0, 401119.0, 424160.0, 47250.763746181736, 75255.0, 76642.0, 76730.0, 78288.60855695799, 899.869518919753, 907.9001309855911, 940.3934462271797, 946.4936259101348, 951.5575257505293, 11654.475548600503, 15627.453039143575, 16318.673626527641, 16432.260548275117, 16683.0, 28179.0, 36202.4569631461, 36722.4491632631, 36926.63073369232, 38580.614193858826, 45077.54922450865, 45903.54096458993, 48796.0, 48943.26585101298, 51298.48701512937, 7283.781486555412, 7714.884276735741, 7760.805979850323, 7922.0, 8058.274755272054, 8495.405321627426, 10850.348979061282, 10999.505022273857, 11005.779884402427, 12222.144449888423, 8722.476651400937, 9335.299852511225, 9594.136527712539, 9684.225261978883, 9972.900270997197, 9748.853767193355, 10016.749581260236, 10309.639162629272, 10514.474276286002, 10701.143908487458, 8997.190252877272, 9445.811083778148, 9711.320207585684, 9842.458664773507, 9959.900400995897, 7896.131425543301, 7945.443818932619, 7977.840443190988, 8001.479903806234, 8062.072861620989, 8531.360147988802, 9194.678186263449, 9284.35009549352, 9463.905360946303, 9592.184664303611], "value": 8276.461183587497, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.3281225793235, 1377.1117629129212, 1433.663089174044, 1632.5918520369908, 1686.4771920704582, 6829.590224586515, 7018.298170182992, 7344.26557344266, 7962.402819788517, 7966.203379662031, 7334.339909408152, 7593.1647518773025, 7598.3541398981015, 7732.226777322265, 7751.224877512246, 2154.095279982403, 2183.4759657682202, 2190.9014094365793, 2194.067521303448, 2194.462356722597, 1812.9909350453229, 1876.5121068522146, 2094.6753253245697, 2101.674240492726, 2120.7455105387376, 2134.157007981846, 2171.6091103601348, 2176.6952626632283, 2184.8907554622206, 2185.8797766122857, 2157.11558261113, 2170.4248374180856, 2173.228503881115, 2182.6725991101384, 2182.923597674074, 2116.5661039486868, 2132.168454302823, 2134.8505604607685, 2136.59404713104, 2139.122959586562, 7966.681332746683, 8027.157148499382, 8035.477693949874, 8036.718714845011, 8038.517688938682, 4417.668674849367, 4554.567316104964, 4561.40701708779, 4599.05719327537, 4606.262997920317, 2812.7609153222, 2922.655578433923, 2949.0268211490156, 2949.0710426215887, 2973.6431628204537, 875.9474431534128, 898.9865152022725, 914.4147745442922, 930.8184903943734, 932.5710173320281, 10739.570417183304, 10759.3544387337, 10763.085137763299, 10780.407077610807, 10790.460476976199, 3778.3765678663262, 4083.224187404384, 4246.171996460661, 4250.723702959298, 4271.572842715704, 9360.765980850534, 9366.110219529133, 9374.390664606779, 9378.624855005859, 9381.061893810564, 4514.0971805639, 4516.638668906546, 4521.434820647419, 4523.864284071483, 4528.298113792367, 4088.8942194769716, 4119.340905455173, 4125.051238215227, 4133.194027164674, 4139.79301034947, 3598.298331825269, 3679.5032670589126, 3684.5946945835935, 3686.760360576554, 3697.6856967157555, 1048.9318194317498, 1098.5441041967495, 1156.653004098767, 1179.097990037622, 1186.8813118688236, 759.6733404635949, 765.8991119146568, 769.692123150733, 794.408165916396, 797.7726347990795, 4898.90202195954, 4995.925061124086, 5013.172826483662, 5058.064258112261, 5263.894722105613, 800.2837460472845, 843.8317678424002, 849.9685751054983, 861.1818772166467, 862.2972277593866, 3359.5632567766033, 3393.321335732814, 3407.437772767515, 3417.743669224818, 3538.610752817188, 9530.52347382628, 10002.49987500622, 10009.299349045643, 10015.349002314972, 10028.899711002943, 9794.069563391466, 9854.11312981835, 9907.306488545733, 9910.15763660097, 9937.503124843728, 6899.793006209822, 7138.143422789299, 7402.074740657418, 7483.251674832473, 7483.700651973969, 5246.186841039645, 5249.580033597226, 5274.709890955881, 5292.100342941786, 5343.251944727819, 8482.363822713198, 8651.091635378194, 8763.079876612865, 8836.95581522114, 8869.778255543662, 8735.606897689491, 8812.559372031501, 8873.0, 8895.866562001445, 8915.643374264957, 6792.422644075309, 6898.827529311808, 6909.343612356818, 6912.827179320558, 6919.965400172967, 7389.593572353358, 7433.331000210007, 7527.6988920444755, 7544.47188696786, 7573.734919277799, 5321.574274057989, 6519.641419721961, 6536.379043990813, 6569.343065693392, 6862.107925969592, 6398.616083035033, 6401.071844582602, 6415.8396040097505, 6416.582922110048, 6431.067495213074, 5701.372848986525, 5811.360750317377, 5811.912821307768, 5817.70911454434, 5832.212651292021, 4143.19207754485, 4179.9582004179565, 4282.957170428256, 4306.160298741841, 4306.267934451088, 4262.147570485853, 4327.913441731211, 4329.437173167468, 4368.191884501377, 4415.668824838214], "value": 4067.6491835504817, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.8021065464829, 287.07132426599946, 296.42641488719323, 297.6264787691447, 297.91509419815344, 4665.276882083274, 4859.878503037417, 4895.975520122399, 4900.0, 4946.307516947626, 4655.255159174532, 4811.831585894485, 4812.879678008052, 4818.831340903069, 4829.637777166718, 3816.790076545789, 3820.923581528368, 3823.0633494793815, 3830.0616348994536, 3839.731218814677, 3605.7836529808164, 3840.462335273064, 3847.7883716395586, 3853.614638536152, 3860.7683538987612, 3451.137215696076, 3692.686121679647, 3701.444783282516, 3714.532759559972, 3721.3673675475097, 3686.8525258989607, 3701.5002974598356, 3710.3506886294927, 3718.382503610938, 3727.5340582427198, 3229.6931791479774, 3241.9027429177163, 3250.902472925792, 3252.2357246047395, 3274.934501309984, 4638.443386793572, 4695.835645752416, 4714.292856071583, 4780.211265141247, 4788.449328327252, 4290.485141782975, 4292.699511034229, 4300.311950087971, 4303.182395344875, 4305.289627211507, 5081.644284900059, 5083.669561478529, 5092.261622064817, 5094.617903657204, 5130.153524668425, 3426.3661222673636, 3562.9643703562892, 3582.390993531106, 3585.551806024247, 3624.3294990426857, 5245.816396426145, 5269.789208431658, 5270.393904700932, 5277.366715994067, 5295.7881684732565, 3650.525431693916, 3656.835442405045, 3660.5973342932257, 3666.450032495147, 3668.119651283726, 3588.766730162531, 3687.1519550503535, 3701.408394390438, 3707.5921648618632, 3715.108373990223, 4198.412222288882, 4222.704410691284, 4235.131797981407, 4241.618254357061, 4252.851150209728, 5044.798208071636, 5060.2156665716875, 5072.594192464594, 5094.1849304111165, 5095.694258344512, 1213.7997230456876, 1239.7458521003173, 1282.2306616030307, 1287.7167023255056, 1298.9025823063307, 842.4625059266863, 865.6321063547967, 871.9476831390136, 873.7073080517971, 874.9518776467355, 4503.729776213438, 4508.684392092522, 4514.864554063383, 4516.796744146522, 4529.935465165683, 5184.6299905062015, 5195.402528709171, 5206.71363075027, 5207.661502002357, 5222.97388513055, 4352.9129417412105, 4497.527759585262, 4511.1654343946475, 4515.458145022619, 4536.661684802991, 2761.406297646016, 2763.7701222955593, 2763.9496991143637, 2779.901938734229, 2783.6360183509933, 4911.263310503453, 4911.263310503453, 4931.5315045070665, 4932.4327702314695, 4946.579540739005, 5065.189569668835, 5264.210368444764, 5297.258383826267, 5313.548348390353, 5317.30874986257, 4877.902441951142, 4888.60891128716, 4894.363732714724, 4905.362302900671, 4913.852584422473, 4964.652474326763, 5190.8961820762615, 5194.662346947436, 5217.295665085317, 5231.712255825966, 5043.4365346742625, 5083.745812709423, 5115.8233606271515, 5120.359955005624, 5145.253938179018, 4805.8077676892535, 4836.516348365276, 4887.315775791321, 4904.190808516555, 4907.435644900882, 3627.7460577760357, 3641.435577485494, 3641.890743277706, 3642.7450078494253, 3649.233660931234, 4904.754762261801, 5082.517160869711, 5190.948090519047, 5199.4540573239265, 5204.921926171186, 5044.571211447068, 5049.621278404208, 5050.141475949171, 5053.469385714447, 5059.3169922061525, 4875.634327425528, 5047.394312682502, 5053.0, 5060.468650791761, 5064.518870707277, 4799.424069111729, 4891.554222888556, 4939.53074457926, 4941.579965702956, 4986.576141028053, 5000.2499625056535, 5173.482651734947, 5198.246254293182, 5205.0, 5205.973970130277, 5046.662634401813, 5059.241113832954, 5060.342155519759, 5065.797368105234, 5092.8472145835685], "value": 3803.7276968317447, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.9894602107955, 555.8415851482328, 559.2366419836922, 562.524666656675, 616.1251023546565, 11953.64139075827, 13145.342732863355, 13153.276569788657, 13172.538961136373, 13176.93411532941, 11702.180847340689, 11962.521499140024, 12240.75518489632, 12256.693582660439, 12282.877171228285, 609.5946195779824, 742.4209116888821, 764.1556080531017, 766.4826242286463, 767.2787579675115, 626.4706323156925, 748.7491690283762, 768.8500742355243, 770.3875419041875, 773.6479901644766, 819.713100414853, 821.8685010398337, 822.0341099208437, 822.1983566023109, 822.4489591973362, 639.27761629359, 817.7669364231195, 817.9836403271931, 818.7257268814955, 819.3608984991716, 483.4561118741416, 571.072007987021, 585.5959388022251, 587.6415386614162, 588.3116753398534, 12374.876251237552, 12407.93796031014, 12430.627081187578, 12430.875691243153, 12437.502499899992, 10962.0, 11356.37309550353, 11407.828882566768, 11572.537098516133, 12033.819492707617, 10958.616448424267, 11150.386728729916, 11197.888021119767, 11450.427478626036, 11511.079113670887, 4064.512258528966, 4070.3894415837567, 4075.1442197138635, 4138.275801734714, 4234.661227101855, 66071.339286607, 68748.0, 69705.65147174231, 69820.30179698189, 70870.2912970874, 9790.26573007013, 9936.821626557781, 10001.299909006446, 10217.897821021843, 10361.896381036244, 16316.75524867128, 17470.213840377215, 17494.912525437292, 17506.29974801019, 17545.56136096608, 5815.244018277597, 5876.471117599351, 5969.074793407029, 6001.279846418458, 6194.039923811816, 3596.6583174598372, 3642.7085833133287, 3717.75834570752, 3720.144366795598, 3738.04795253524, 2741.0954385052883, 2856.5001124803075, 3106.751459883204, 3257.28339765256, 3261.0, 540.694507603203, 541.6668748719588, 544.3087279155451, 561.9971900140555, 577.6101131736145, 449.2609657114094, 509.88527581294653, 512.7000704587783, 527.082875796112, 539.4497612435373, 7053.788386348418, 7086.39765619928, 7131.607761573163, 7247.23903990084, 7392.704291828267, 467.12647349456734, 467.29438547793075, 477.54633098555814, 479.5564103204522, 492.59853219625893, 3303.4168474259563, 3379.0, 3384.272381438003, 3468.0, 3535.7701749386206, 76206.6189669048, 79804.0, 79935.41259454467, 90462.0, 107990.0, 79729.60135199403, 80621.0, 81766.18233817705, 82213.0, 82247.5887620558, 70410.29589704037, 70933.64533177404, 73414.63292683609, 73957.0, 77162.22837771551, 7590.203028681909, 7621.070899597623, 7691.538507689556, 7957.124716281089, 8191.713290034821, 42471.3629295567, 44098.77950610226, 44394.11211775811, 44524.55475445334, 47079.52920470751, 56368.15447768369, 63718.681406592674, 65578.34421655722, 65852.34147658462, 65855.0, 10213.693589192337, 10469.31949423285, 10489.423081730272, 10539.841902371318, 10640.574377025141, 11268.661940141808, 11450.71373215643, 12491.0, 12529.060320475819, 12538.686532836751, 10190.847137292798, 10308.12380947598, 10319.122874555747, 10500.527476263434, 11232.775344493228, 9767.560459779184, 10377.0, 10672.039516443558, 10728.356298621795, 10997.725056873642, 9412.95293523528, 9729.22166226686, 9763.221028476253, 9778.41329520231, 9870.358426702242, 9208.248307380765, 9423.434593924387, 9580.425174489554, 9698.418094914328, 9857.704268871945, 9084.364094513585, 9263.814723705353, 9414.764630884009, 9472.763180920532, 9524.523773811143], "value": 6237.57311201836, "unit": "score"}], "name": "dromaeo_css", "value": 6290.57807608755, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 116.0, 115.0, 116.0, 115.0, 115.0, 114.0, 114.0, 115.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 125.0, 173.0, 132.0, 123.0, 122.0, 130.0, 168.0, 127.0], "value": 137.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 163.0, 161.0, 161.0, 162.0, 160.0, 161.0, 159.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 76.0, 115.0, 77.0, 78.0, 77.0, 76.0, 98.0, 77.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 128.0, 83.0, 85.0, 85.0, 118.0, 83.0, 83.0, 122.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 148.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 62.0, 64.0, 64.0, 64.0, 65.0, 62.0, 64.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 69.0, 68.0, 70.0, 68.0, 67.0, 67.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 87.0, 82.0, 82.0, 83.0, 82.0, 83.0, 82.0, 83.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 132.0, 141.0, 129.0, 131.0, 131.0, 136.0, 177.0, 132.0], "value": 138.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 173.0, 172.0, 173.0, 173.0, 180.0, 170.0, 172.0, 171.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 69.0, 70.0, 69.0, 69.0, 69.0, 68.0, 69.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1546.6000000000001, "alertThreshold": 2.0}]} 00:25:04 INFO - Return code: 0 00:25:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:25:04 INFO - # TBPL SUCCESS # 00:25:04 INFO - Running post-action listener: _resource_record_post_action 00:25:04 INFO - [mozharness: 2016-09-21 07:25:04.557123Z] Finished run-tests step (success) 00:25:04 INFO - Running post-run listener: _resource_record_post_run 00:25:04 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 401072128; Read time: 12; Write time: 263284 00:25:04 INFO - TinderboxPrint: CPU usage
13.1% 00:25:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 00:25:04 INFO - TinderboxPrint: I/O write bytes / time
401,072,128 / 263,284 00:25:04 INFO - TinderboxPrint: CPU idle
8,330.7 (86.9%) 00:25:04 INFO - TinderboxPrint: CPU user
1,200.8 (12.5%) 00:25:04 INFO - TinderboxPrint: Swap in / out
0 / 0 00:25:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110346240; Read time: 0; Write time: 65152 00:25:04 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 289579008; Read time: 0; Write time: 198104 00:25:05 INFO - Running post-run listener: _upload_blobber_files 00:25:05 INFO - Blob upload gear active. 00:25:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:25:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 00:25:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:25:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:25:05 INFO - (blobuploader) - INFO - Open directory for files ... 00:25:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:25:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:25:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:25:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:25:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:25:06 INFO - (blobuploader) - INFO - Done attempting. 00:25:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:25:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:25:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:25:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:25:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:25:07 INFO - (blobuploader) - INFO - Done attempting. 00:25:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:25:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:25:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:25:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:25:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:25:12 INFO - (blobuploader) - INFO - Done attempting. 00:25:12 INFO - (blobuploader) - INFO - Iteration through files over. 00:25:12 INFO - Return code: 0 00:25:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:25:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:25:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/00ce507d86c2ee0feea27dee65f53ac9bc2f601712cf8a0a009f32001f0c0501f3a3fe0f5f59e052c4d406c5c93fca4a042639aaee4aa85c0f854b5f71df2cbb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ffd28771d4af039dd6d99103f7cb3c7d370523088622c5b8b8b2a1a1c66866d2bcde4a7f81e4f032e13056cb4e072651b1bcbafb6a3965d751842cd8de06014b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a8323eee9eadf209f02d43a04b259a09c1b477a36fbcddb1f7eea8fb957501c52fc75b81a66f1f93ebe5d158929fdf8f8b1013073bd940c2a2069b302495aa07"} 00:25:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:25:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:25:12 INFO - Contents: 00:25:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/00ce507d86c2ee0feea27dee65f53ac9bc2f601712cf8a0a009f32001f0c0501f3a3fe0f5f59e052c4d406c5c93fca4a042639aaee4aa85c0f854b5f71df2cbb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ffd28771d4af039dd6d99103f7cb3c7d370523088622c5b8b8b2a1a1c66866d2bcde4a7f81e4f032e13056cb4e072651b1bcbafb6a3965d751842cd8de06014b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a8323eee9eadf209f02d43a04b259a09c1b477a36fbcddb1f7eea8fb957501c52fc75b81a66f1f93ebe5d158929fdf8f8b1013073bd940c2a2069b302495aa07"} 00:25:12 INFO - Running post-run listener: copy_logs_to_upload_dir 00:25:12 INFO - Copying logs to upload dir... 00:25:12 INFO - mkdir: /builds/slave/test/build/upload/logs 00:25:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.114579 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-09-21 00:25:12.848451) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 00:25:12.852145) ========= 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=62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/00ce507d86c2ee0feea27dee65f53ac9bc2f601712cf8a0a009f32001f0c0501f3a3fe0f5f59e052c4d406c5c93fca4a042639aaee4aa85c0f854b5f71df2cbb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ffd28771d4af039dd6d99103f7cb3c7d370523088622c5b8b8b2a1a1c66866d2bcde4a7f81e4f032e13056cb4e072651b1bcbafb6a3965d751842cd8de06014b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a8323eee9eadf209f02d43a04b259a09c1b477a36fbcddb1f7eea8fb957501c52fc75b81a66f1f93ebe5d158929fdf8f8b1013073bd940c2a2069b302495aa07"} build_url:https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/aWlK8CyuQrGRq9IyxIENKg/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/Fx-Team-Non-PGO/sha512/00ce507d86c2ee0feea27dee65f53ac9bc2f601712cf8a0a009f32001f0c0501f3a3fe0f5f59e052c4d406c5c93fca4a042639aaee4aa85c0f854b5f71df2cbb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ffd28771d4af039dd6d99103f7cb3c7d370523088622c5b8b8b2a1a1c66866d2bcde4a7f81e4f032e13056cb4e072651b1bcbafb6a3965d751842cd8de06014b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a8323eee9eadf209f02d43a04b259a09c1b477a36fbcddb1f7eea8fb957501c52fc75b81a66f1f93ebe5d158929fdf8f8b1013073bd940c2a2069b302495aa07"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 00:25:12.876898) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 00:25:12.877208) ========= 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=62c568580acbfdaac3eec7be000001dc-1474440201.674132-553329220 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 00:25:12.961546) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 00:25:12.961866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 00:25:12.962153) ========= ========= Total master_lag: 0.17 =========