builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1474035190.63 results: success (0) buildid: 20160916043000 builduid: 9c58f6cc043c43779fb33e5af2eb4924 revision: f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.631987) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.632436) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.632770) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.692950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.693322) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-16 07:13:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.2M=0s 2016-09-16 07:13:11 (67.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129344 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.840058) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.840389) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015144 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.895039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:10.895381) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 --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=516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-16 07:13:12,011 truncating revision to first 12 chars 2016-09-16 07:13:12,011 Setting DEBUG logging. 2016-09-16 07:13:12,012 attempt 1/10 2016-09-16 07:13:12,012 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f1dbeb5dee22?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-16 07:13:12,327 unpacking tar archive at: mozilla-central-f1dbeb5dee22/testing/mozharness/ program finished with exit code 0 elapsedTime=0.656796 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:11.566995) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:11.567307) ========= script_repo_revision: f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:11.567769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:11.568080) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-16 07:13:11.577313) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-16 07:13:11.577616) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:13:12 INFO - MultiFileLogger online at 20160916 07:13:12 in /builds/slave/test 07:13:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:13:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:13:12 INFO - {'append_to_log': False, 07:13:12 INFO - 'base_work_dir': '/builds/slave/test', 07:13:12 INFO - 'blob_upload_branch': 'Firefox', 07:13:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:13:12 INFO - 'branch': 'Firefox', 07:13:12 INFO - 'buildbot_json_path': 'buildprops.json', 07:13:12 INFO - 'config_files': ('talos/linux_config.py',), 07:13:12 INFO - 'default_actions': ('clobber', 07:13:12 INFO - 'read-buildbot-config', 07:13:12 INFO - 'download-and-extract', 07:13:12 INFO - 'populate-webroot', 07:13:12 INFO - 'create-virtualenv', 07:13:12 INFO - 'install', 07:13:12 INFO - 'run-tests'), 07:13:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:13:12 INFO - 'download_minidump_stackwalk': True, 07:13:12 INFO - 'download_symbols': 'ondemand', 07:13:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:13:12 INFO - 'tooltool.py': '/tools/tooltool.py', 07:13:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:13:12 INFO - '/tools/misc-python/virtualenv.py')}, 07:13:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:13:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:13:12 INFO - 'installer_path': 'installer.exe', 07:13:12 INFO - 'log_level': 'info', 07:13:12 INFO - 'log_name': 'talos', 07:13:12 INFO - 'log_to_console': True, 07:13:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:13:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:13:12 INFO - 'opt_config_files': (), 07:13:12 INFO - 'pip_index': False, 07:13:12 INFO - 'sps_profile': False, 07:13:12 INFO - 'sps_profile_interval': 0, 07:13:12 INFO - 'suite': 'dromaeojs', 07:13:12 INFO - 'system_bits': '32', 07:13:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:13:12 INFO - 'title': 'talos-linux64-ix-061', 07:13:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:13:12 INFO - 'use_talos_json': True, 07:13:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:13:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:13:12 INFO - 'work_dir': 'build'} 07:13:12 INFO - [mozharness: 2016-09-16 14:13:12.794036Z] Running clobber step. 07:13:12 INFO - Running pre-action listener: _resource_record_pre_action 07:13:12 INFO - Running main action method: clobber 07:13:12 INFO - rmtree: /builds/slave/test/build 07:13:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:13:13 INFO - Running post-action listener: _resource_record_post_action 07:13:13 INFO - [mozharness: 2016-09-16 14:13:13.767185Z] Finished clobber step (success) 07:13:13 INFO - [mozharness: 2016-09-16 14:13:13.767285Z] Running read-buildbot-config step. 07:13:13 INFO - Running pre-action listener: _resource_record_pre_action 07:13:13 INFO - Running main action method: read_buildbot_config 07:13:13 INFO - Using buildbot properties: 07:13:13 INFO - { 07:13:13 INFO - "project": "", 07:13:13 INFO - "product": "firefox", 07:13:13 INFO - "script_repo_revision": "production", 07:13:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:13:13 INFO - "repository": "", 07:13:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:13:13 INFO - "buildid": "20160916043000", 07:13:13 INFO - "pgo_build": "True", 07:13:13 INFO - "basedir": "/builds/slave/test", 07:13:13 INFO - "buildnumber": 874, 07:13:13 INFO - "slavename": "talos-linux64-ix-061", 07:13:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:13:13 INFO - "platform": "ubuntu64_hw", 07:13:13 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 07:13:13 INFO - "branch": "mozilla-central", 07:13:13 INFO - "repo_path": "mozilla-central", 07:13:13 INFO - "revision": "f1dbeb5dee22cabc56b2cf25d2eab1435abbf131", 07:13:13 INFO - "stage_platform": "linux64-pgo", 07:13:13 INFO - "builduid": "9c58f6cc043c43779fb33e5af2eb4924", 07:13:13 INFO - "slavebuilddir": "test" 07:13:13 INFO - } 07:13:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 07:13:13 INFO - Running post-action listener: _resource_record_post_action 07:13:13 INFO - [mozharness: 2016-09-16 14:13:13.769752Z] Finished read-buildbot-config step (success) 07:13:13 INFO - [mozharness: 2016-09-16 14:13:13.769843Z] Running download-and-extract step. 07:13:13 INFO - Running pre-action listener: _resource_record_pre_action 07:13:13 INFO - Running main action method: download_and_extract 07:13:13 INFO - mkdir: /builds/slave/test/build/tests 07:13:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:13 INFO - trying https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:13:13 INFO - Downloading https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:13:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:13:14 INFO - Downloaded 1361 bytes. 07:13:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:13:14 INFO - Using the following test package requirements: 07:13:14 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:13:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:13:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:13:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:14 INFO - u'jsshell-linux-x86_64.zip'], 07:13:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:13:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:13:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:13:14 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:13:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:13:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:13:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:13:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:13:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:13:14 INFO - Fetch https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:13:14 INFO - Expected file size: 21935366 07:13:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:13:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:13:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:13:19 INFO - Fetch https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:13:19 INFO - Expected file size: 21935366 07:13:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:13:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:13:24 INFO - Fetch https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:13:25 INFO - Expected file size: 14414048 07:13:25 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')]} 07:13:25 INFO - trying https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:13:25 INFO - Downloading https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:13:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:13:26 INFO - Downloaded 59527103 bytes. 07:13:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:13:26 INFO - mkdir: /builds/slave/test/properties 07:13:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:13:26 INFO - Writing to file /builds/slave/test/properties/build_url 07:13:26 INFO - Contents: 07:13:26 INFO - build_url:https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:13:27 INFO - Running post-action listener: _resource_record_post_action 07:13:27 INFO - Running post-action listener: set_extra_try_arguments 07:13:27 INFO - [mozharness: 2016-09-16 14:13:27.316613Z] Finished download-and-extract step (success) 07:13:27 INFO - [mozharness: 2016-09-16 14:13:27.316886Z] Running populate-webroot step. 07:13:27 INFO - Running pre-action listener: _resource_record_pre_action 07:13:27 INFO - Running main action method: populate_webroot 07:13:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:13:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:13:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:13:27 INFO - '--tppagecycles', 07:13:27 INFO - '7'], 07:13:27 INFO - 'tests': ['tsvgm']}}, 07:13:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:13:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:13:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:13:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:13:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:27 INFO - 'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:13:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:13:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:27 INFO - 'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['damp', 'tps']}, 07:13:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:27 INFO - 'tests': ['damp', 'tps']}, 07:13:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['dromaeo_dom']}, 07:13:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:13:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['basic_compositor_video']}, 07:13:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:13:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['a11yr', 07:13:27 INFO - 'ts_paint', 07:13:27 INFO - 'tpaint', 07:13:27 INFO - 'sessionrestore', 07:13:27 INFO - 'sessionrestore_no_auto_restore', 07:13:27 INFO - 'tabpaint']}, 07:13:27 INFO - 'other-e10s': {'tests': ['a11yr', 07:13:27 INFO - 'ts_paint', 07:13:27 INFO - 'tpaint', 07:13:27 INFO - 'sessionrestore', 07:13:27 INFO - 'sessionrestore_no_auto_restore', 07:13:27 INFO - 'tabpaint']}, 07:13:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:13:27 INFO - 'ts_paint', 07:13:27 INFO - 'tpaint', 07:13:27 INFO - 'sessionrestore', 07:13:27 INFO - 'sessionrestore_no_auto_restore', 07:13:27 INFO - 'tabpaint']}, 07:13:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:13:27 INFO - 'ts_paint', 07:13:27 INFO - 'tpaint', 07:13:27 INFO - 'sessionrestore', 07:13:27 INFO - 'sessionrestore_no_auto_restore', 07:13:27 INFO - 'tabpaint']}, 07:13:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['a11yr', 07:13:27 INFO - 'ts_paint', 07:13:27 INFO - 'tpaint', 07:13:27 INFO - 'sessionrestore', 07:13:27 INFO - 'sessionrestore_no_auto_restore', 07:13:27 INFO - 'tabpaint']}, 07:13:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['a11yr', 07:13:27 INFO - 'ts_paint', 07:13:27 INFO - 'tpaint', 07:13:27 INFO - 'sessionrestore', 07:13:27 INFO - 'sessionrestore_no_auto_restore', 07:13:27 INFO - 'tabpaint']}, 07:13:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['tsvgx', 07:13:27 INFO - 'tsvgr_opacity', 07:13:27 INFO - 'tart', 07:13:27 INFO - 'tscrollx', 07:13:27 INFO - 'cart']}, 07:13:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:13:27 INFO - 'tsvgr_opacity', 07:13:27 INFO - 'tart', 07:13:27 INFO - 'tscrollx', 07:13:27 INFO - 'cart']}, 07:13:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:27 INFO - 'talos_options': ['--disable-e10s'], 07:13:27 INFO - 'tests': ['tp5o']}, 07:13:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:27 INFO - 'tests': ['tp5o']}, 07:13:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:27 INFO - 'talos_options': ['--disable-e10s', 07:13:27 INFO - '--xperf_path', 07:13:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:13:27 INFO - 'tests': ['tp5n']}, 07:13:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:27 INFO - 'talos_options': ['--xperf_path', 07:13:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:13:27 INFO - 'tests': ['tp5n']}}, 07:13:27 INFO - 'talos.zip': {'path': '', 07:13:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:13:27 INFO - Running post-action listener: _resource_record_post_action 07:13:27 INFO - [mozharness: 2016-09-16 14:13:27.337343Z] Finished populate-webroot step (success) 07:13:27 INFO - [mozharness: 2016-09-16 14:13:27.337437Z] Running create-virtualenv step. 07:13:27 INFO - Running pre-action listener: _resource_record_pre_action 07:13:27 INFO - Running main action method: create_virtualenv 07:13:27 INFO - Creating virtualenv /builds/slave/test/build/venv 07:13:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:13:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:13:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:13:27 INFO - Using real prefix '/usr' 07:13:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:13:27 INFO - Installing distribute.............................................................................................................................................................................................done. 07:13:29 INFO - Installing pip.................done. 07:13:29 INFO - Return code: 0 07:13:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:29 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 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:29 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 07:13:29 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 07:13:29 INFO - Using env: {'DISPLAY': ':0', 07:13:29 INFO - 'HOME': '/home/cltbld', 07:13:29 INFO - 'LANG': 'en_US.UTF-8', 07:13:29 INFO - 'LANGUAGE': 'en_US:en', 07:13:29 INFO - 'LOGNAME': 'cltbld', 07:13:29 INFO - 'MAIL': '/var/mail/cltbld', 07:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:29 INFO - 'MOZ_NO_REMOTE': '1', 07:13:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:29 INFO - 'NO_EM_RESTART': '1', 07:13:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:29 INFO - 'PWD': '/builds/slave/test', 07:13:29 INFO - 'SHELL': '/bin/bash', 07:13:29 INFO - 'SHLVL': '1', 07:13:29 INFO - 'TERM': 'linux', 07:13:29 INFO - 'TMOUT': '86400', 07:13:29 INFO - 'USER': 'cltbld', 07:13:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:29 INFO - '_': '/tools/buildbot/bin/python'} 07:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:13:30 INFO - Cleaning up... 07:13:30 INFO - Return code: 0 07:13:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:13:30 INFO - Using env: {'DISPLAY': ':0', 07:13:30 INFO - 'HOME': '/home/cltbld', 07:13:30 INFO - 'LANG': 'en_US.UTF-8', 07:13:30 INFO - 'LANGUAGE': 'en_US:en', 07:13:30 INFO - 'LOGNAME': 'cltbld', 07:13:30 INFO - 'MAIL': '/var/mail/cltbld', 07:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:30 INFO - 'MOZ_NO_REMOTE': '1', 07:13:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:30 INFO - 'NO_EM_RESTART': '1', 07:13:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:30 INFO - 'PWD': '/builds/slave/test', 07:13:30 INFO - 'SHELL': '/bin/bash', 07:13:30 INFO - 'SHLVL': '1', 07:13:30 INFO - 'TERM': 'linux', 07:13:30 INFO - 'TMOUT': '86400', 07:13:30 INFO - 'USER': 'cltbld', 07:13:30 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:30 INFO - '_': '/tools/buildbot/bin/python'} 07:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:30 INFO - Downloading/unpacking psutil>=3.1.1 07:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:13:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:13:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:13:32 INFO - Installing collected packages: psutil 07:13:32 INFO - Running setup.py install for psutil 07:13:32 INFO - building 'psutil._psutil_linux' extension 07:13:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:13:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:13:32 INFO - building 'psutil._psutil_posix' extension 07:13:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:13:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:13:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:13:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:13:32 INFO - Successfully installed psutil 07:13:32 INFO - Cleaning up... 07:13:32 INFO - Return code: 0 07:13:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:13:32 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')]} 07:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:32 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')]} 07:13:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:32 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 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:32 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 07:13:32 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 07:13:32 INFO - Using env: {'DISPLAY': ':0', 07:13:32 INFO - 'HOME': '/home/cltbld', 07:13:32 INFO - 'LANG': 'en_US.UTF-8', 07:13:32 INFO - 'LANGUAGE': 'en_US:en', 07:13:32 INFO - 'LOGNAME': 'cltbld', 07:13:32 INFO - 'MAIL': '/var/mail/cltbld', 07:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:32 INFO - 'MOZ_NO_REMOTE': '1', 07:13:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:32 INFO - 'NO_EM_RESTART': '1', 07:13:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:32 INFO - 'PWD': '/builds/slave/test', 07:13:32 INFO - 'SHELL': '/bin/bash', 07:13:32 INFO - 'SHLVL': '1', 07:13:32 INFO - 'TERM': 'linux', 07:13:32 INFO - 'TMOUT': '86400', 07:13:32 INFO - 'USER': 'cltbld', 07:13:32 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:32 INFO - '_': '/tools/buildbot/bin/python'} 07:13:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:13:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:13:35 INFO - Installing collected packages: mozsystemmonitor 07:13:35 INFO - Running setup.py install for mozsystemmonitor 07:13:35 INFO - Successfully installed mozsystemmonitor 07:13:35 INFO - Cleaning up... 07:13:35 INFO - Return code: 0 07:13:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:13:35 INFO - Using env: {'DISPLAY': ':0', 07:13:35 INFO - 'HOME': '/home/cltbld', 07:13:35 INFO - 'LANG': 'en_US.UTF-8', 07:13:35 INFO - 'LANGUAGE': 'en_US:en', 07:13:35 INFO - 'LOGNAME': 'cltbld', 07:13:35 INFO - 'MAIL': '/var/mail/cltbld', 07:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:35 INFO - 'MOZ_NO_REMOTE': '1', 07:13:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:35 INFO - 'NO_EM_RESTART': '1', 07:13:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:35 INFO - 'PWD': '/builds/slave/test', 07:13:35 INFO - 'SHELL': '/bin/bash', 07:13:35 INFO - 'SHLVL': '1', 07:13:35 INFO - 'TERM': 'linux', 07:13:35 INFO - 'TMOUT': '86400', 07:13:35 INFO - 'USER': 'cltbld', 07:13:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:35 INFO - '_': '/tools/buildbot/bin/python'} 07:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:35 INFO - Downloading/unpacking jsonschema==2.5.1 07:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:13:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:37 INFO - Downloading functools32-3.2.3-2.tar.gz 07:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:13:37 INFO - warning: no files found matching '*.txt' 07:13:37 INFO - no previously-included directories found matching 'build' 07:13:37 INFO - no previously-included directories found matching 'dist' 07:13:37 INFO - no previously-included directories found matching '.git*' 07:13:37 INFO - Installing collected packages: jsonschema, functools32 07:13:37 INFO - Running setup.py install for functools32 07:13:37 INFO - warning: no files found matching '*.txt' 07:13:37 INFO - no previously-included directories found matching 'build' 07:13:37 INFO - no previously-included directories found matching 'dist' 07:13:37 INFO - no previously-included directories found matching '.git*' 07:13:37 INFO - Successfully installed jsonschema functools32 07:13:37 INFO - Cleaning up... 07:13:37 INFO - Return code: 0 07:13:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:13:37 INFO - Using env: {'DISPLAY': ':0', 07:13:37 INFO - 'HOME': '/home/cltbld', 07:13:37 INFO - 'LANG': 'en_US.UTF-8', 07:13:37 INFO - 'LANGUAGE': 'en_US:en', 07:13:37 INFO - 'LOGNAME': 'cltbld', 07:13:37 INFO - 'MAIL': '/var/mail/cltbld', 07:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:37 INFO - 'MOZ_NO_REMOTE': '1', 07:13:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:37 INFO - 'NO_EM_RESTART': '1', 07:13:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:37 INFO - 'PWD': '/builds/slave/test', 07:13:37 INFO - 'SHELL': '/bin/bash', 07:13:37 INFO - 'SHLVL': '1', 07:13:37 INFO - 'TERM': 'linux', 07:13:37 INFO - 'TMOUT': '86400', 07:13:37 INFO - 'USER': 'cltbld', 07:13:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:37 INFO - '_': '/tools/buildbot/bin/python'} 07:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:13:37 INFO - Cleaning up... 07:13:37 INFO - Return code: 0 07:13:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:13:37 INFO - Using env: {'DISPLAY': ':0', 07:13:37 INFO - 'HOME': '/home/cltbld', 07:13:37 INFO - 'LANG': 'en_US.UTF-8', 07:13:37 INFO - 'LANGUAGE': 'en_US:en', 07:13:37 INFO - 'LOGNAME': 'cltbld', 07:13:37 INFO - 'MAIL': '/var/mail/cltbld', 07:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:37 INFO - 'MOZ_NO_REMOTE': '1', 07:13:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:37 INFO - 'NO_EM_RESTART': '1', 07:13:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:37 INFO - 'PWD': '/builds/slave/test', 07:13:37 INFO - 'SHELL': '/bin/bash', 07:13:37 INFO - 'SHLVL': '1', 07:13:37 INFO - 'TERM': 'linux', 07:13:37 INFO - 'TMOUT': '86400', 07:13:37 INFO - 'USER': 'cltbld', 07:13:37 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:37 INFO - '_': '/tools/buildbot/bin/python'} 07:13:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:38 INFO - Downloading/unpacking blobuploader==1.2.4 07:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:40 INFO - Downloading blobuploader-1.2.4.tar.gz 07:13:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:13:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:13:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:40 INFO - Downloading docopt-0.6.1.tar.gz 07:13:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:13:40 INFO - Installing collected packages: blobuploader, requests, docopt 07:13:40 INFO - Running setup.py install for blobuploader 07:13:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:13:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:13:40 INFO - Running setup.py install for requests 07:13:41 INFO - Running setup.py install for docopt 07:13:41 INFO - Successfully installed blobuploader requests docopt 07:13:41 INFO - Cleaning up... 07:13:41 INFO - Return code: 0 07:13:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:13:41 INFO - Using env: {'DISPLAY': ':0', 07:13:41 INFO - 'HOME': '/home/cltbld', 07:13:41 INFO - 'LANG': 'en_US.UTF-8', 07:13:41 INFO - 'LANGUAGE': 'en_US:en', 07:13:41 INFO - 'LOGNAME': 'cltbld', 07:13:41 INFO - 'MAIL': '/var/mail/cltbld', 07:13:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:41 INFO - 'MOZ_NO_REMOTE': '1', 07:13:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:41 INFO - 'NO_EM_RESTART': '1', 07:13:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:41 INFO - 'PWD': '/builds/slave/test', 07:13:41 INFO - 'SHELL': '/bin/bash', 07:13:41 INFO - 'SHLVL': '1', 07:13:41 INFO - 'TERM': 'linux', 07:13:41 INFO - 'TMOUT': '86400', 07:13:41 INFO - 'USER': 'cltbld', 07:13:41 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:41 INFO - '_': '/tools/buildbot/bin/python'} 07:13:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:13:41 INFO - Running setup.py (path:/tmp/pip-tu2M3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:13:41 INFO - Running setup.py (path:/tmp/pip-LrdtLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:13:41 INFO - Running setup.py (path:/tmp/pip-2hcJ_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:13:41 INFO - Running setup.py (path:/tmp/pip-mfhUDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:13:41 INFO - Running setup.py (path:/tmp/pip-OWQwWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:13:42 INFO - Running setup.py (path:/tmp/pip-zAdZJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:13:42 INFO - Running setup.py (path:/tmp/pip-sQRyum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:13:42 INFO - Running setup.py (path:/tmp/pip-wcYM7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:13:42 INFO - Running setup.py (path:/tmp/pip-r892Wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:13:42 INFO - Running setup.py (path:/tmp/pip-Pz4J6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:13:42 INFO - Running setup.py (path:/tmp/pip-3LBU2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:13:42 INFO - Running setup.py (path:/tmp/pip-NFGCR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:13:42 INFO - Running setup.py (path:/tmp/pip-z5QEGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:13:42 INFO - Running setup.py (path:/tmp/pip-ul6vfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:42 INFO - Running setup.py (path:/tmp/pip-_MlKZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:13:42 INFO - Running setup.py (path:/tmp/pip-4aX5TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:13:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:13:42 INFO - Running setup.py (path:/tmp/pip-PUZttt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:13:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:13:42 INFO - Running setup.py install for manifestparser 07:13:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:13:42 INFO - Running setup.py install for mozcrash 07:13:43 INFO - Running setup.py install for mozdebug 07:13:43 INFO - Running setup.py install for mozdevice 07:13:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Running setup.py install for mozfile 07:13:43 INFO - Running setup.py install for mozhttpd 07:13:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Running setup.py install for mozinfo 07:13:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Running setup.py install for mozInstall 07:13:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Running setup.py install for mozleak 07:13:43 INFO - Running setup.py install for mozlog 07:13:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:13:43 INFO - Running setup.py install for moznetwork 07:13:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:13:44 INFO - Running setup.py install for mozprocess 07:13:44 INFO - Running setup.py install for mozprofile 07:13:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:13:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:13:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:13:44 INFO - Running setup.py install for mozrunner 07:13:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:13:44 INFO - Running setup.py install for mozscreenshot 07:13:44 INFO - Running setup.py install for moztest 07:13:44 INFO - Running setup.py install for mozversion 07:13:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:13:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:13:44 INFO - Cleaning up... 07:13:44 INFO - Return code: 0 07:13:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:44 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 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:44 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 07:13:44 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 07:13:44 INFO - Using env: {'DISPLAY': ':0', 07:13:44 INFO - 'HOME': '/home/cltbld', 07:13:44 INFO - 'LANG': 'en_US.UTF-8', 07:13:44 INFO - 'LANGUAGE': 'en_US:en', 07:13:44 INFO - 'LOGNAME': 'cltbld', 07:13:44 INFO - 'MAIL': '/var/mail/cltbld', 07:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:44 INFO - 'MOZ_NO_REMOTE': '1', 07:13:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:44 INFO - 'NO_EM_RESTART': '1', 07:13:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:44 INFO - 'PWD': '/builds/slave/test', 07:13:44 INFO - 'SHELL': '/bin/bash', 07:13:44 INFO - 'SHLVL': '1', 07:13:44 INFO - 'TERM': 'linux', 07:13:44 INFO - 'TMOUT': '86400', 07:13:44 INFO - 'USER': 'cltbld', 07:13:44 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:44 INFO - '_': '/tools/buildbot/bin/python'} 07:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:13:44 INFO - Running setup.py (path:/tmp/pip-baWo_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:13:44 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)) 07:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:13:44 INFO - Running setup.py (path:/tmp/pip-xBotBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:13:45 INFO - Running setup.py (path:/tmp/pip-zp4tW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:13:45 INFO - Running setup.py (path:/tmp/pip-VsqGZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:13:45 INFO - Running setup.py (path:/tmp/pip-K4k44c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:13:45 INFO - Running setup.py (path:/tmp/pip-_48QVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:13:45 INFO - Running setup.py (path:/tmp/pip-TNKiVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:13:45 INFO - Running setup.py (path:/tmp/pip-0ymWsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:13:45 INFO - Running setup.py (path:/tmp/pip-yiOKYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:13:45 INFO - Running setup.py (path:/tmp/pip-I9p8gz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:13:45 INFO - Running setup.py (path:/tmp/pip-P22URd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:13:45 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)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:13:45 INFO - Running setup.py (path:/tmp/pip-_JVYyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:13:45 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)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:13:45 INFO - Running setup.py (path:/tmp/pip-Jz4Aqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:13:45 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)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:13:45 INFO - Running setup.py (path:/tmp/pip-nR3Voa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:13:45 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)) 07:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:45 INFO - Running setup.py (path:/tmp/pip-QcLyE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:13:46 INFO - Running setup.py (path:/tmp/pip-CFRsGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:13:46 INFO - Running setup.py (path:/tmp/pip-A6R8EV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:13:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:13: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 07:13: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 07:13: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 07:13: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 07:13: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 07:13: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 07:13:48 INFO - Downloading blessings-1.6.tar.gz 07:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:13:48 INFO - Installing collected packages: blessings 07:13:48 INFO - Running setup.py install for blessings 07:13:48 INFO - Successfully installed blessings 07:13:48 INFO - Cleaning up... 07:13:48 INFO - Return code: 0 07:13:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:13:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:48 INFO - Reading from file tmpfile_stdout 07:13:48 INFO - Current package versions: 07:13:48 INFO - argparse == 1.2.1 07:13:48 INFO - blessings == 1.6 07:13:48 INFO - blobuploader == 1.2.4 07:13:48 INFO - docopt == 0.6.1 07:13:48 INFO - functools32 == 3.2.3-2 07:13:48 INFO - jsonschema == 2.5.1 07:13:48 INFO - manifestparser == 1.1 07:13:48 INFO - mozInstall == 1.12 07:13:48 INFO - mozcrash == 0.17 07:13:48 INFO - mozdebug == 0.1 07:13:48 INFO - mozdevice == 0.48 07:13:48 INFO - mozfile == 1.2 07:13:48 INFO - mozhttpd == 0.7 07:13:48 INFO - mozinfo == 0.9 07:13:48 INFO - mozleak == 0.1 07:13:48 INFO - mozlog == 3.3 07:13:48 INFO - moznetwork == 0.27 07:13:48 INFO - mozprocess == 0.23 07:13:48 INFO - mozprofile == 0.28 07:13:48 INFO - mozrunner == 6.12 07:13:48 INFO - mozscreenshot == 0.1 07:13:48 INFO - mozsystemmonitor == 0.3 07:13:48 INFO - moztest == 0.7 07:13:48 INFO - mozversion == 1.4 07:13:48 INFO - psutil == 3.1.1 07:13:48 INFO - requests == 1.2.3 07:13:48 INFO - wsgiref == 0.1.2 07:13:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:13:48 INFO - Using env: {'DISPLAY': ':0', 07:13:48 INFO - 'HOME': '/home/cltbld', 07:13:48 INFO - 'LANG': 'en_US.UTF-8', 07:13:48 INFO - 'LANGUAGE': 'en_US:en', 07:13:48 INFO - 'LOGNAME': 'cltbld', 07:13:48 INFO - 'MAIL': '/var/mail/cltbld', 07:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:48 INFO - 'MOZ_NO_REMOTE': '1', 07:13:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:48 INFO - 'NO_EM_RESTART': '1', 07:13:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:48 INFO - 'PWD': '/builds/slave/test', 07:13:48 INFO - 'SHELL': '/bin/bash', 07:13:48 INFO - 'SHLVL': '1', 07:13:48 INFO - 'TERM': 'linux', 07:13:48 INFO - 'TMOUT': '86400', 07:13:48 INFO - 'USER': 'cltbld', 07:13:48 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:48 INFO - '_': '/tools/buildbot/bin/python'} 07:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 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)) 07:13:48 INFO - Cleaning up... 07:13:48 INFO - Return code: 0 07:13:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:48 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 0x7f2fd77bef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c9f790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, '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': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', '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 07:13:48 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 07:13:48 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 07:13:48 INFO - Using env: {'DISPLAY': ':0', 07:13:48 INFO - 'HOME': '/home/cltbld', 07:13:48 INFO - 'LANG': 'en_US.UTF-8', 07:13:48 INFO - 'LANGUAGE': 'en_US:en', 07:13:48 INFO - 'LOGNAME': 'cltbld', 07:13:48 INFO - 'MAIL': '/var/mail/cltbld', 07:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:48 INFO - 'MOZ_NO_REMOTE': '1', 07:13:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:48 INFO - 'NO_EM_RESTART': '1', 07:13:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:48 INFO - 'PWD': '/builds/slave/test', 07:13:48 INFO - 'SHELL': '/bin/bash', 07:13:48 INFO - 'SHLVL': '1', 07:13:48 INFO - 'TERM': 'linux', 07:13:48 INFO - 'TMOUT': '86400', 07:13:48 INFO - 'USER': 'cltbld', 07:13:48 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:48 INFO - '_': '/tools/buildbot/bin/python'} 07:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:13:48 INFO - Cleaning up... 07:13:48 INFO - Return code: 0 07:13:48 INFO - Running post-action listener: _resource_record_post_action 07:13:48 INFO - Running post-action listener: _start_resource_monitoring 07:13:48 INFO - Starting resource monitoring. 07:13:48 INFO - [mozharness: 2016-09-16 14:13:48.990211Z] Finished create-virtualenv step (success) 07:13:48 INFO - [mozharness: 2016-09-16 14:13:48.990673Z] Running install step. 07:13:48 INFO - Running pre-action listener: _resource_record_pre_action 07:13:48 INFO - Running main action method: install 07:13:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:49 INFO - Reading from file tmpfile_stdout 07:13:49 INFO - Detecting whether we're running mozinstall >=1.0... 07:13:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:13:49 INFO - Reading from file tmpfile_stdout 07:13:49 INFO - Output received: 07:13:49 INFO - Usage: mozinstall [options] installer 07:13:49 INFO - Options: 07:13:49 INFO - -h, --help show this help message and exit 07:13:49 INFO - -d DEST, --destination=DEST 07:13:49 INFO - Directory to install application into. [default: 07:13:49 INFO - "/builds/slave/test"] 07:13:49 INFO - --app=APP Application being installed. [default: firefox] 07:13:49 INFO - mkdir: /builds/slave/test/build/application 07:13:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:14:06 INFO - Reading from file tmpfile_stdout 07:14:06 INFO - Output received: 07:14:06 INFO - /builds/slave/test/build/application/firefox/firefox 07:14:06 INFO - Running post-action listener: _resource_record_post_action 07:14:06 INFO - [mozharness: 2016-09-16 14:14:06.268686Z] Finished install step (success) 07:14:06 INFO - [mozharness: 2016-09-16 14:14:06.268990Z] Running run-tests step. 07:14:06 INFO - Running pre-action listener: _resource_record_pre_action 07:14:06 INFO - Running main action method: run_tests 07:14:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:14:06 INFO - Python 2.7.3 07:14:06 INFO - Return code: 0 07:14:06 INFO - grabbing minidump binary from tooltool 07:14:06 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')]} 07:14:06 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 0x1c9c930>, '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 0x1c9d830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c9dcc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:14:06 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 07:14:06 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 07:14:06 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 07:14:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:14:06 INFO - Return code: 0 07:14:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:14:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:14:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:14:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:14:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:14:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 07:14:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --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 07:14:06 INFO - Using env: {'DISPLAY': ':0', 07:14:06 INFO - 'HOME': '/home/cltbld', 07:14:06 INFO - 'LANG': 'en_US.UTF-8', 07:14:06 INFO - 'LANGUAGE': 'en_US:en', 07:14:06 INFO - 'LOGNAME': 'cltbld', 07:14:06 INFO - 'MAIL': '/var/mail/cltbld', 07:14:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:14:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:14:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:06 INFO - 'MOZ_NO_REMOTE': '1', 07:14:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:14:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:14:06 INFO - 'NO_EM_RESTART': '1', 07:14:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:14:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:06 INFO - 'PWD': '/builds/slave/test', 07:14:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:14:06 INFO - 'SHELL': '/bin/bash', 07:14:06 INFO - 'SHLVL': '1', 07:14:06 INFO - 'TERM': 'linux', 07:14:06 INFO - 'TMOUT': '86400', 07:14:06 INFO - 'USER': 'cltbld', 07:14:06 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924', 07:14:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:06 INFO - '_': '/tools/buildbot/bin/python'} 07:14:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--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 07:14:06 INFO - mozversion application_buildid: 20160916043000 07:14:06 INFO - mozversion application_changeset: f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 07:14:06 INFO - mozversion application_display_name: Nightly 07:14:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:14:06 INFO - mozversion application_name: Firefox 07:14:06 INFO - mozversion application_remotingname: firefox 07:14:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:14:06 INFO - mozversion application_vendor: Mozilla 07:14:06 INFO - mozversion application_version: 51.0a1 07:14:06 INFO - mozversion platform_buildid: 20160916043000 07:14:06 INFO - mozversion platform_changeset: f1dbeb5dee22cabc56b2cf25d2eab1435abbf131 07:14:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:14:06 INFO - mozversion platform_version: 51.0a1 07:14:06 INFO - using testdate: 1474035246 07:14:06 INFO - actual date: 1474035246 07:14:06 INFO - starting webserver on 'localhost:55622' 07:14:06 INFO - SUITE-START | Running 2 tests 07:14:06 INFO - TEST-START | dromaeo_css 07:14:06 INFO - operating with platform_type : linux_ 07:14:06 INFO - Initialising browser for dromaeo_css test... 07:14:06 INFO - TEST-INFO | started process 11153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyOi_Sz/profile http://localhost:55622/getInfo.html) 07:14:11 INFO - PROCESS | 11153 | __metrics Screen width/height:1600/1200 07:14:11 INFO - PROCESS | 11153 | colorDepth:24 07:14:11 INFO - PROCESS | 11153 | Browser inner width/height: 1024/697 07:14:11 INFO - PROCESS | 11153 | __metrics 07:14:11 INFO - PROCESS | 11153 | 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" 07:14:11 INFO - PROCESS | 11153 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:14:12 INFO - PROCESS | 11153 | console.error: PushService: 07:14:12 INFO - PROCESS | 11153 | stateChangeProcessEnqueue: Error transitioning state 07:14:12 INFO - PROCESS | 11153 | UnknownError 07:14:13 INFO - TEST-INFO | 11153: exit 0 07:14:13 INFO - Browser initialized. 07:14:13 INFO - Running cycle 1/1 for dromaeo_css test... 07:14:13 INFO - TEST-INFO | started process 11238 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyOi_Sz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:14:25 INFO - PROCESS | 11238 | RSS: Main: 173015040 07:14:25 INFO - PROCESS | 11238 | 07:14:40 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 07:15:02 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 07:15:08 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 07:15:16 INFO - PROCESS | 11238 | 1474035316895 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] 07:15:18 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.0166 seconds. 07:15:19 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 2.04998 seconds. 07:15:23 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:15:27 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:15:31 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:15:37 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.05005 seconds. 07:15:44 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 07:15:49 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.0667 seconds. 07:15:55 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 07:15:59 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.16673 seconds. 07:16:02 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:16:10 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 07:16:12 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 07:16:45 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 07:16:49 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 07:16:51 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 07:16:53 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:17:10 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:17:13 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:17:22 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 07:17:26 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:55622/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55622/tests/dromaeo/cssquery-ext.html) 07:17:27 INFO - PROCESS | 11238 | RSS: Main: 320819200 07:17:27 INFO - PROCESS | 11238 | 07:17:31 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03343 seconds. 07:17:38 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:17:52 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:17:54 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:18:00 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:18:08 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 07:18:18 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:18:31 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:18:41 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 07:18:51 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:19:01 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:19:13 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:19:15 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:19:19 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:19:29 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:19:31 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:19:51 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:19:59 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 07:20:01 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 07:20:07 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 07:20:08 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 07:20:30 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:55622/tests/dromaeo/cssquery-ext.html (next: http://localhost:55622/tests/dromaeo/cssquery-jquery.html) 07:20:30 INFO - PROCESS | 11238 | RSS: Main: 275066880 07:20:30 INFO - PROCESS | 11238 | 07:20:37 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.05007 seconds. 07:20:49 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:20:55 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 07:21:11 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:21:20 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:21:25 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:21:37 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:21:39 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 07:21:44 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 07:21:51 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:22:02 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:22:04 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:22:32 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 07:22:38 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 07:22:48 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:22:58 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:23:00 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:23:11 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:23:14 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:23:18 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:23:22 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:23:24 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:23:26 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:23:27 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 2.00007 seconds. 07:23:28 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:23:32 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:55622/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55622/tests/dromaeo/cssquery-mootools.html) 07:23:33 INFO - PROCESS | 11238 | RSS: Main: 260407296 07:23:33 INFO - PROCESS | 11238 | 07:23:38 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 07:23:43 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:24:02 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:24:16 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 07:24:21 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:24:23 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:24:26 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:24:32 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:24:42 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:24:44 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:24:52 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:25:02 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:25:04 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 07:25:16 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:25:34 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:25:38 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:25:44 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:25:52 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:26:03 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 07:26:11 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:26:16 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:26:21 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:26:23 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00007 seconds. 07:26:28 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 07:26:31 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:26:33 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 07:26:35 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 07:26:35 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:55622/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55622/tests/dromaeo/cssquery-prototype.html) 07:26:36 INFO - PROCESS | 11238 | RSS: Main: 214298624 07:26:36 INFO - PROCESS | 11238 | 07:26:42 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:26:44 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:26:54 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:27:01 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 07:27:06 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:27:10 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:27:13 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 07:27:23 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:27:25 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 07:27:37 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:27:45 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:27:58 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:28:03 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:28:13 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:28:21 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:28:28 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:28:30 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 07:28:32 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 07:28:36 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 07:28:48 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:28:57 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:29:00 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:29:04 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:29:11 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:29:12 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 07:29:14 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 07:29:24 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:29:32 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00008 seconds. 07:29:37 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:29:38 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:55622/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55622/tests/dromaeo/cssquery-yui.html) 07:29:39 INFO - PROCESS | 11238 | RSS: Main: 216403968 07:29:39 INFO - PROCESS | 11238 | 07:29:42 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 07:29:46 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:29:47 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 07:29:49 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 07:30:00 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:30:10 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 07:30:17 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:30:20 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:30:31 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:30:32 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01677 seconds. 07:30:34 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:30:42 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:30:48 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:30:54 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:31:00 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 07:31:08 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 07:31:14 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:31:16 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:31:23 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:31:53 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 07:31:55 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 07:32:10 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 07:32:17 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 07:32:19 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 07:32:32 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 07:32:38 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01676 seconds. 07:32:40 INFO - PROCESS | 11238 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 07:32:42 INFO - PROCESS | 11238 | Cycle 1(1): loaded http://localhost:55622/tests/dromaeo/cssquery-yui.html (next: http://localhost:55622/tests/dromaeo/cssquery-dojo.html) 07:32:42 INFO - PROCESS | 11238 | RSS: Main: 308879360 07:32:42 INFO - PROCESS | 11238 | 07:32:42 INFO - PROCESS | 11238 | __start_tp_report 07:32:42 INFO - PROCESS | 11238 | _x_x_mozilla_page_load 07:32:42 INFO - PROCESS | 11238 | _x_x_mozilla_page_load_details 07:32:42 INFO - PROCESS | 11238 | |i|pagename|runs| 07:32:42 INFO - PROCESS | 11238 | |0;dojo.html;2973.2566858285427;3008.5186370180777;3008.8345141017244;3028.3034901972533;3056.9541456878137;5117.641765076446;5130.38435387754;5209.8697532561655;5223.843284701456;5236.397814251362;5143.7685304161305;5196.350456192976;5221.034108689895;5273.18265668821;5283.656562323446;4729.78715957781;4747.928781068286;4783.665143439961;4814.205656066745;4830.637702172334;4858.659893807435;4874.2201247800185;4896.436909755388;4901.436334821487;4958.3306253655655;4647.23320652092;4774.212254977924;4792.568668819823;4819.831305904294;4891.461939186686;4651.5813576778255;4652.720836749772;4656.99874526975;4667.929981050287;4746.38297021385;4627.329037289608;4636.721796692209;4639.095376401603;4648.814047438098;4692.061587682443;5064.772085256172;5107.131787596118;5109.310243117169;5122.462141475129;5146.871328216787;5341.65279256847;5403.8108666196495;5488.17677348397;5556.305461817273;5570;5513.283273174502;5531.446855314477;5594.916076258859;5638.097904335327;5657.8585535361535;5190.92213616796;5194.220866869962;5200.583953283766;5205.55752761012;5255.947440525622;5426.131818908955;5493.642913210629;5555.444455554412;5648.124540696199;5655.632383895034;5405.729713514387;5483.177523371526;5487.2317875497465;5495.203195536705;5543.057680194296;5499.120140777456;5538.889222215614;5541.9168712469345;5549.72251387429;5553.694546799884;5406.324209473816;5416.620836541404;5499.697516636624;5568.415316391802;5624.634398764068;5430.294061771944;5433.483819037185;5476.616636835383;5559.305086864142;5563.638363506359;4263.978680106579;4269.210196113729;4322.200392927319;4322.8703138905885;4369.69412141147;3837.136644255076;3861.2084522672785;3897.512810898638;3899.1421887184774;3924.5486769021927;5221.060209162311;5238.528532432099;5278.788848446096;5286.20706893969;5286.36563612369;5536.695481748462;5570.888582228333;5581.441855814467;5583.748731307101;5585.218069470277;5158.92261616076;5278.076336641149;5285.788568457296;5299.125644268653;5347.064263753828;4809.855704328876;4836.830710925101;4885.5847252983185;4891.706497610155;4943.604511639061;5748.770049198069;5748.827535173951;5761.135829625506;5762.250907382014;5763.711814409179;5559.360673522435;5565.1374037024325;5565.749541270571;5571.916421253765;5572.637778544382;5575.303087114111;5611.971940140274;5619.859503512282;5629.7748090077575;5701.201831743476;5377.784888604569;5390.892182156414;5394.029074766423;5394.244805727123;5453.509184173444;5420.4850539198715;5428.348598168245;5470;5482.479164479368;5543.223948647274;5315.548178404724;5335.253064571041;5375.56995440356;5377.838664840061;5380;5417.160340147283;5468.56251499887;5473.042217611982;5476.041692703681;5481.150421684645;5344.305240318734;5344.75948582307;5345.759440825094;5353.0364534382625;5356.0626890294825;5334.199870019528;5440.211169380497;5459.617826752248;5462.3445186577865;5521.503064724194;5274.920876186783;5314.654547454403;5319.813806516753;5320.361556613231;5380.569554435558 07:32:42 INFO - PROCESS | 11238 | |1;ext.html;550.9312531444425;596.8627215740377;599.1581827532317;602.7167231401243;611.9143319935208;32039.51940720891;44182.779086104565;44827.32759008609;44842.32736508955;44874.32688509667;45706;45795.31307030397;45966;46025.769871150595;46045.76977115118;8098.595070246493;8944.016158222606;9083.137101975335;9092.27261819053;9099.135582119721;8190.754277371659;10560.94719526405;10826.783464330709;10842.403667798308;11131.610393636225;11035.117190624771;11096.278741881792;11097.83353249702;11098.778024439507;11099.72250693731;10857.67426977192;10880.292780969212;10909.945450272699;10923.890761092367;10933;10537;10623.362598244054;10634.46827658614;10683.30558513694;10693.358398496115;42232;42538.78730606358;42555.7872210637;42568.78715606433;42872;3340.450204298992;3410.1474631342167;3483.4672743992564;3653.963460365389;3754.6808521275725;32314.192145196324;32510;32708.182295442566;32737.181570460692;34378.14054648654;4016.4176194451643;4030.3349947258666;4079.1841631673783;4128.731632443912;4223.028703398236;319408;322446;322537;322871;323275;56748;56927.43072569221;56961.715191424606;56976;56980.43019569751;36085.8195709025;36796.44805327922;36877.446838297445;38774.95925529663;60852.391476084675;15204.771928421083;15233.923830380778;15320.923395382952;15346.923265383602;15362.615934601725;11046.447677616086;11324.37715925632;11889.405529723479;12245.265284082983;12331.260124392566;18654.906725466284;18892.527686807665;18999.905000475184;19019.90490047541;19086.90456547736;7121.928780712126;7144.607046612382;7205.711771529185;7283.963580182065;7284.235155308722;890.888638920135;984.921206303494;985.1822986920838;1063.2079101069598;1111.5387114347454;31725.206869827976;31834;32016.199595009846;32046.679533204835;32619.51070733941;1071.1377341240377;1076.4886678827495;1082.1018554599664;1136.852209212797;1143.8398624192619;15590.064596124268;16500.917495412446;16572.75140872888;16609.584760380847;16961.575960600836;150225;150345;150542;151366;151461.24269378584;58030.129548056815;58371.41628583745;58578.414215857294;58648.41351586515;58648.706756465945;1207.704112492445;1227.4967263422134;1306.2097431054076;1338.5716570697284;1379.2345248387041;28823;32934.670653293164;33180.33639327248;33552.49671254884;33723.09896001734;49038.50961490339;49499.752501237264;49524.75237623789;49573.25640115329;49602.503974959785;358310;360512;361050;361531;361623;28547.571786423636;44192.12751171164;44249.55750442454;45019.549804501534;45921.31118033299;45891.31163032624;46191;46491.53508464872;49608.50391496183;49971.750141250515;40293;40960.38559421671;41128.794356028026;41562;43255;41644.375334369404;42011;42802.78598606987;42872.571274286856;43215.78392108019;41687.791561042;42803.57196427996;43104.78447607742;43110.56889431066;43136.13727725491;34770;36909.726359267515;37179;37759.433608496445;38086.23827523489;28155.859220703765;28373;28392;28698.856505718173;28704.712952870203 07:32:42 INFO - PROCESS | 11238 | |2;jquery.html;353.5492247384583;364.1115677746297;388.3785942492013;393.32741012867996;398.8903051660793;14378.352974116164;18191.54521136974;18484.35304764332;18632.534186645342;19621.901890490528;18285.268589256415;18622.348217812378;18766.812331876677;18818.905905470452;18898.14958326875;5424.5117939385345;5666.235058267143;5676.545876329905;5678.886422271573;5680;5481.095619222843;5503.972480137593;5560.6941618210985;5564.054110801153;5638.154276858483;5546.362168350651;5563.193336966137;5563.749631266603;5565.499105080542;5569.888602227953;5361.919571206435;5486.588505862076;5502.504774570308;5536.944630553703;5552.333719953611;4961.578265847407;4965.577925876305;4971.676841005323;4975.62682798788;4980.05378977993;22952.54094918109;23038.65442018371;23095.653565196535;23136.190233341753;23421;2098;2210.502636906699;2395.4370722880108;2515.408878913454;2672.3452754075374;9773.12041916231;9999.300048996573;10175.796484070277;10191.745206369826;10421;1765.3997640802188;1773.9556511087196;1870.9438716838517;1872.3353209610527;1891.914863831131;72808.07878818167;82123;85433;85501.14498854995;87778.56110719405;10215.540300686487;10287.588496460208;10543.683689489328;10642.680719578426;10715.624953126604;14302.713945721027;14377.6405589859;14443.855561444461;14887.255637218095;14912.776308355384;7153.7138514459875;7481.438892083007;7600.1639819619795;7681.116671582728;7823.452358334862;7193.784186474414;7237.601931893796;7316.926830731731;7356.852862942711;7439.279236653184;4403.6660569898;4447.266201076851;4489.9551004490195;4569.154706379264;4720.103180395714;1816.2644129127725;1854.1378259109308;1888.2447021191358;1934.9032548372525;1960.2551030608277;909.5452273863068;946.7822400847843;957.9089986451276;995.7012896131132;1021.2085633633993;17578.761203425267;19088.236470541022;19295.710564341545;19510.122044508033;19855.503612409517;899.7300809757178;955.1069749783974;968.1045033344128;1019.0421004255976;1027.1525991057408;9875.654352097643;10409.27135100551;10684.839727404094;10734.409607471664;10758.94620526908;431491;445645;445728;446194;450185;85952.57023714841;93224.53387733018;93483;94109;96814.51592741991;995.1392045880406;995.3082607587802;1031.0617338222182;1037.3983089807894;1040.4485622620023;16461;16701.665966680357;17041.744373834652;17423.738643920606;18023.2790688371;40718.796406017784;42121.36817947795;42337.364939525316;43111.56888431076;43125.78437107794;58377.62654612357;58545.12182317354;58967.41032589791;59390;60139;8249.670013199404;8260.008798944165;8381.413301069111;8586.840776495093;8590.312774977861;12000.75998480043;13074.738505230032;13302.401391937192;13330;13344.399502022627;11490.655280341603;11709.414529273332;11795.764084718428;11809.881901180877;12022.415180736183;11254.324740515594;11278.943605281922;11346.829797552879;11389.772204555697;11551.133664974992;11675.3578553177;11786.233894796811;11955.820662690241;11997.64007079789;12309.56916507918;8305.626246819029;8501.787455313666;8509.659613615386;8671.4363566368;8739.257163141205;8767.736967890973;10817.459127043774;11510.251833630788;11597.594084207012;11910.736959977576 07:32:42 INFO - PROCESS | 11238 | |3;mootools.html;1314.3756715560105;1408.5422237772723;1451.0205611212432;1540.1144342003345;1719.7248440249555;7538.434617403696;8225.87661185082;8577.614007369668;8588.742337729871;8858.069902660209;8491.23578877901;8554.52950087745;8609.26821220197;8624.525651089187;8636.093210212932;2334.5447637710695;2357.6463530470483;2361.055577768889;2361.504084142332;2366.3374255208482;2307.6769252304607;2346.3899386159637;2346.5541547105995;2352.3766201956496;2352.764723527651;2266.9659955100688;2278.8860556972168;2288.519410923708;2325.2791634593245;2346.272655476799;2208.4589275627495;2210.1932794530044;2210.823134149264;2213.1036930043438;2236.966445503319;2267.1044937249694;2271.068861766668;2276.8633881967135;2281.0989659084566;2282.4864405508793;8317.41778075535;8430;8518.446300990416;8530.445521041112;8562.529060901648;4937.925931111036;5108.923366149511;5116.232565115225;5116.872078198075;5122.718250496221;3139.136737397211;3203.983980080108;3240.7245384142375;3258.4949332853444;3268.4607039838397;1094.5840580579409;1113.3320007995137;1114.3536748685742;1118.8992990630882;1142.6971852459105;11875.465604047838;11876.0499160067;11988;12005.699857503545;12015.21901076436;4128.174365126986;4314.460692413449;4328.653707703439;4429.645628349725;4440.666949978765;8828.646854125891;8831.293496520262;8835.381523293308;8835.469871807583;8836.204741573289;4878.585320247818;4894.216925291937;4894.535019173173;4898.877528061756;4901.068796928572;4048.352263637804;4169.187008533307;4408.9118217636105;4496.4154659894;4609.078184363141;3612.4942508048894;3637.909052273714;3733.8506459741848;3745.793981331053;3766.7363284570365;1069.1072954083427;1073.688630297205;1078;1078.9298695584762;1100.4552746390589;943.613118621362;946.9100435458621;950.695777351241;953.6566835939057;981.3572110267786;5778.682172480554;5788.565857560616;5845.181674565564;5908.940910590925;5993.340732519419;437.2850389613006;437.5974103824489;438.7893810970752;454.60222305482705;460.415272603798;3836.1943991761486;4024.939625905614;4057;4061.5126184858004;4157.708960372805;9783;10382.584696612052;10493.632722854663;10549.156067514581;10558.260921735558;10318.845217321747;10463;10465.162786977024;10477.37135771856;10486.161107111413;7720.15078341382;7900.170482099412;7923.841523169504;7963.48237364581;7964.561949092739;5618.111172785638;5685.602007859576;5754.424557544212;5777.191193232867;5942.64344139353;9781.119699227103;9816.754581135243;9828.16460600857;9891.258155638212;9899.851502227328;9731.708048758548;9824.164945979672;9887.55506002246;9963.352382095141;9975.60097596088;6791.083203767428;6973.267806880204;7169.928300717134;7203.495755297079;7412.851742965218;8377.413581049268;8450.788730281547;8458.873116903374;8472.279856212292;8481.787955301166;7179.569225846466;7266;7276.563406195433;7439.739609113655;7570.886436703564;7229.49393542468;7301.379382752525;7376.37300829414;7537.660805263667;7595.88606170919;6779.15260592426;6901.999210114605;6903;6904.965475172793;6906;4854.83008094715;4943.30793688891;4959.280904268933;5097.617678674188;5107.744612769272;4891.364122664031;5064.670796398223;5073.797048118034;5139.820106296262;5249.921251181159 07:32:42 INFO - PROCESS | 11238 | |4;prototype.html;308.2232773411004;317.9062176657886;322.9499427588724;328.85530366638676;328.9210589458531;5256.369235691717;5311;5311.256424100629;5313.698143954736;5351.357837059559;5212.947870521293;5300.628955973083;5307.920381194285;5321.627486075967;5344.22508736233;4104.445899803518;4180.184863951538;4228.577142285762;4232.830686772525;4234.470691163605;4175.561566035568;4176.498820141587;4177.582241775828;4189.539150693421;4191.413202151702;4118.423420721087;4128.669706423494;4129.174165166964;4136.462259906208;4157.210130075291;3938.6061393860673;4068.2880495913096;4137.6069273419125;4138.1103062841485;4178.185253875496;3574.9811303778297;3645.471406646048;3654.780713157219;3657.6159503252043;3670.724695647837;5146.691198528063;5154.149565321717;5159.277701121846;5168.896622067575;5174.249733788617;4464.308032255006;4477.507474177838;4766.499517550642;4770.475247722748;4807.966287248259;5512;5512.641678290938;5517.200005999148;5530.972345138289;5578.2748242728185;4024.1348110156164;4034.7175697701173;4034.919301613951;4037.354023356278;4059.512858457004;5567.4432556744405;5699.145128230799;5778.8266352009505;5788.392218817048;5805.042168042268;4076.5719599442223;4079.63283304504;4086.346184610448;4091.5499295077516;4114.0948991221885;4147.170565886774;4149.585041495826;4152.314868046798;4157.521884983205;4170.833166673299;4394.670399720034;4668.089722504079;4714.15145273854;4744.525547445228;4752.884966190012;5493.313335833021;5511.283533140746;5526.972365138229;5538.723063846792;5553.194786755978;1377.2631642071444;1396.2739375524873;1397.9440822366992;1400.6848459096623;1402.0956483068364;832.3549249331817;883.0022075055147;883.6067949762422;898.3038145437208;899.1458114790975;4645.9303110453375;4681.063787242497;4693.202155633482;4703.7883295251795;4715.811367545329;4971.229459433794;4972.975135124301;5004.749762511859;5098.694078355311;5125.436202017775;4709.811607535728;4711.858644240679;4817.229243321051;4861.586765124934;4987.226979818134;2872.224499385154;2930.633670791151;2931.724699755613;2953.9409211816073;3011.789174757746;5859.560532960045;5867.823965281048;5886.970565147147;5914.319853216849;5932.377100404396;5557.944420555824;5835.824925252249;5848.678322692293;5866.6186697864505;5874.50066744323;5854.297484301911;5856.8535786604825;5866.530677545786;5870.354261031369;5886.8822623547285;5662.716864156693;5665.461781130786;5668.858278543069;5669.4047125051275;5689.54483641316;5385.973070134624;5663.3770285267765;5674.319081710221;5687.5449964001955;5695.658260504383;5917.526597872074;5925.911111333419;5929.762809487745;5931.822045338647;5933.554983376351;3974.2647610192207;3996.6403023728003;4007.979960100181;4010.27814993304;4011.2980228460488;5473.233747275464;5690.715464226855;5726.74229659658;5729.140628905531;5842.38654941242;5588.904160939647;5592.972035139798;5603.327600687943;5620.988222120058;5622.718864056699;5347.625666203328;5581.553475721851;5601;5617.691026993554;5634.943650563442;5527.44725527444;5549.722513874371;5554.861128471821;5555.638883472561;5556.416576259435;5545.306836645419;5576.275084239023;5589.860253493695;5622.943770562406;5658.292713410824;5220.425753167225;5449.782008719607;5454.972725136349;5500.944990550043;5517.337919449692 07:32:42 INFO - PROCESS | 11238 | |5;yui.html;648.3160265919453;668.1881513960537;668.2782594797619;694.9887913086461;737.494816051005;12471.625851224466;13075.934620326896;13099.214047157177;13354.26551539665;13955.441782328695;13692.452301907937;13754.381052852621;13790.724185516283;13793.448262069505;13811.240381778998;661.6394065234459;784.8351846112323;807.3460496997451;811.3995643224001;812.553095797312;670.1121014655577;788.3850596534712;802.4904185841974;813.4509206285763;819.9139090395512;857.1428571428571;860.6729442811722;860.7417774667606;861.0227391910196;861.5003298087125;686.5228666077079;847.9830403391928;852.6674596907211;853.2747164909839;853.5007020892754;515.1345739158213;605.0742364182807;617.0343412559375;619.3899009475663;620.1163342237294;12611.747765044647;12621.368931553385;12623.242605443702;12634.305113218768;12668;13221.669458263621;13424.173101705259;13851.376688049062;13899.722005559832;14256;10893.618723344724;11049.17131215162;11097.83353249702;12566.246025238515;12708.110432269745;3733.5893051764283;3747.5877653458097;3748.5689145748315;3792.051987003249;3825.2540754552874;78527;78674.6066269665;79114.20885791183;80379.19620803776;80845.59577202135;11083.390413527333;11199.384033878216;11360.772784544428;11377.715557110972;11388.715282118013;19494.5126371839;19511;19629.901850490656;19651.606967860847;19656.90171549133;7385.29839665231;7396.926030739732;7457.2542745724995;7495.66269517873;7495.887561686579;4264.360345948133;4344.652427805768;4346.304591265382;4422.778861056934;4445.022095139064;3273.459879119966;3422.9657703422645;3425.0923505270625;3425.3149370125575;3426.811525366079;589.755251570602;594.5451729426992;601.1403692719454;602.2411761180856;603.9154518367432;532.6564365983919;623.2583225961092;713.5682911838263;734.8603765284686;782.792559971608;9108.49903255314;9461.33770636049;9488.28837837165;9581.808363832684;9596;617.555360140698;696.1437431958719;696.4150113904344;729.529453502488;760.1182628151317;4326.848560300375;4388.253996820548;4388.495323037828;4517.186906356887;4606.88482787933;115566;119508;123726;124379;128604.3569782145;95902.52048739712;96697.13211471462;97186.5140674292;98354;99406;89757.55121224483;90838.54580727054;91401.54299228461;91581.5420922891;91639;8394.916050839414;8462.83074338522;8507.532085735344;8692.869606955774;8713.128687131237;51977.7401112992;52243.47756522386;52536.47463525316;53184.73407632937;53318.466815332904;70325;78115.60942195253;79640.60179699064;79935;82648.58675706583;10724.89275107239;11462.484188211383;11689.707757306136;11771.529138834694;11889.405529723652;11604.593839215588;11868.821967670665;12071.758564828478;12147.453364598437;13005.089643724848;11327.376994265394;11392.658220253406;11710.765784684429;11796.94101529487;11844.335024619391;11259.7748045037;11435.199536032398;11748.765024699629;11762.176647634584;11897.881021190024;11334.319940803578;11460.713482162679;11506.194566380607;12004.39978001114;12100.697482563006;9154.588043537924;9372.859407109036;9481.620735170516;9754.219662426847;9757.756056098655;9391.20174785151;10349.9482502587;10364.170866330525;10442.269041167348;10461.529231184768 07:32:42 INFO - PROCESS | 11238 | __end_tp_report 07:32:42 INFO - PROCESS | 11238 | __start_cc_report 07:32:42 INFO - PROCESS | 11238 | _x_x_mozilla_cycle_collect,1099 07:32:42 INFO - PROCESS | 11238 | __end_cc_report 07:32:42 INFO - PROCESS | 11238 | __startTimestamp1474036362440__endTimestamp 07:32:42 INFO - PROCESS | 11238 | 07:32:42 INFO - PROCESS | 11238 | ------- Summary: start ------- 07:32:42 INFO - PROCESS | 11238 | Number of tests: 6 07:32:42 INFO - PROCESS | 11238 | 07:32:42 INFO - PROCESS | 11238 | [#0] dojo.html Cycles:165 Average:5154.30 Median:5344.31 stddev:558.55 (10.5%) stddev-sans-first:533.41 07:32:42 INFO - PROCESS | 11238 | Values: 2973.3 3008.5 3008.8 3028.3 3057.0 5117.6 5130.4 5209.9 5223.8 5236.4 5143.8 5196.4 5221.0 5273.2 5283.7 4729.8 4747.9 4783.7 4814.2 4830.6 4858.7 4874.2 4896.4 4901.4 4958.3 4647.2 4774.2 4792.6 4819.8 4891.5 4651.6 4652.7 4657.0 4667.9 4746.4 4627.3 4636.7 4639.1 4648.8 4692.1 5064.8 5107.1 5109.3 5122.5 5146.9 5341.7 5403.8 5488.2 5556.3 5570.0 5513.3 5531.4 5594.9 5638.1 5657.9 5190.9 5194.2 5200.6 5205.6 5255.9 5426.1 5493.6 5555.4 5648.1 5655.6 5405.7 5483.2 5487.2 5495.2 5543.1 5499.1 5538.9 5541.9 5549.7 5553.7 5406.3 5416.6 5499.7 5568.4 5624.6 5430.3 5433.5 5476.6 5559.3 5563.6 4264.0 4269.2 4322.2 4322.9 4369.7 3837.1 3861.2 3897.5 3899.1 3924.5 5221.1 5238.5 5278.8 5286.2 5286.4 5536.7 5570.9 5581.4 5583.7 5585.2 5158.9 5278.1 5285.8 5299.1 5347.1 4809.9 4836.8 4885.6 4891.7 4943.6 5748.8 5748.8 5761.1 5762.3 5763.7 5559.4 5565.1 5565.7 5571.9 5572.6 5575.3 5612.0 5619.9 5629.8 5701.2 5377.8 5390.9 5394.0 5394.2 5453.5 5420.5 5428.3 5470.0 5482.5 5543.2 5315.5 5335.3 5375.6 5377.8 5380.0 5417.2 5468.6 5473.0 5476.0 5481.2 5344.3 5344.8 5345.8 5353.0 5356.1 5334.2 5440.2 5459.6 5462.3 5521.5 5274.9 5314.7 5319.8 5320.4 5380.6 07:32:42 INFO - PROCESS | 11238 | 07:32:42 INFO - PROCESS | 11238 | [#1] ext.html Cycles:180 Average:46739.85 Median:361290.50 stddev:76757.42 (21.2%) stddev-sans-first:76894.40 07:32:42 INFO - PROCESS | 11238 | Values: 550.9 596.9 599.2 602.7 611.9 32039.5 44182.8 44827.3 44842.3 44874.3 45706.0 45795.3 45966.0 46025.8 46045.8 8098.6 8944.0 9083.1 9092.3 9099.1 8190.8 10560.9 10826.8 10842.4 11131.6 11035.1 11096.3 11097.8 11098.8 11099.7 10857.7 10880.3 10909.9 10923.9 10933.0 10537.0 10623.4 10634.5 10683.3 10693.4 42232.0 42538.8 42555.8 42568.8 42872.0 3340.5 3410.1 3483.5 3654.0 3754.7 32314.2 32510.0 32708.2 32737.2 34378.1 4016.4 4030.3 4079.2 4128.7 4223.0 319408.0 322446.0 322537.0 322871.0 323275.0 56748.0 56927.4 56961.7 56976.0 56980.4 36085.8 36796.4 36877.4 38775.0 60852.4 15204.8 15233.9 15320.9 15346.9 15362.6 11046.4 11324.4 11889.4 12245.3 12331.3 18654.9 18892.5 18999.9 19019.9 19086.9 7121.9 7144.6 7205.7 7284.0 7284.2 890.9 984.9 985.2 1063.2 1111.5 31725.2 31834.0 32016.2 32046.7 32619.5 1071.1 1076.5 1082.1 1136.9 1143.8 15590.1 16500.9 16572.8 16609.6 16961.6 150225.0 150345.0 150542.0 151366.0 151461.2 58030.1 58371.4 58578.4 58648.4 58648.7 1207.7 1227.5 1306.2 1338.6 1379.2 28823.0 32934.7 33180.3 33552.5 33723.1 49038.5 49499.8 49524.8 49573.3 49602.5 358310.0 360512.0 361050.0 361531.0 361623.0 28547.6 44192.1 44249.6 45019.5 45921.3 45891.3 46191.0 46491.5 49608.5 49971.8 40293.0 40960.4 41128.8 41562.0 43255.0 41644.4 42011.0 42802.8 42872.6 43215.8 41687.8 42803.6 43104.8 43110.6 43136.1 34770.0 36909.7 37179.0 37759.4 38086.2 28155.9 28373.0 28392.0 28698.9 28704.7 07:32:42 INFO - PROCESS | 11238 | 07:32:42 INFO - PROCESS | 11238 | [#2] jquery.html Cycles:180 Average:27616.21 Median:396.11 stddev:73592.33 (18578.8%) stddev-sans-first:73770.30 07:32:42 INFO - PROCESS | 11238 | Values: 353.5 364.1 388.4 393.3 398.9 14378.4 18191.5 18484.4 18632.5 19621.9 18285.3 18622.3 18766.8 18818.9 18898.1 5424.5 5666.2 5676.5 5678.9 5680.0 5481.1 5504.0 5560.7 5564.1 5638.2 5546.4 5563.2 5563.7 5565.5 5569.9 5361.9 5486.6 5502.5 5536.9 5552.3 4961.6 4965.6 4971.7 4975.6 4980.1 22952.5 23038.7 23095.7 23136.2 23421.0 2098.0 2210.5 2395.4 2515.4 2672.3 9773.1 9999.3 10175.8 10191.7 10421.0 1765.4 1774.0 1870.9 1872.3 1891.9 72808.1 82123.0 85433.0 85501.1 87778.6 10215.5 10287.6 10543.7 10642.7 10715.6 14302.7 14377.6 14443.9 14887.3 14912.8 7153.7 7481.4 7600.2 7681.1 7823.5 7193.8 7237.6 7316.9 7356.9 7439.3 4403.7 4447.3 4490.0 4569.2 4720.1 1816.3 1854.1 1888.2 1934.9 1960.3 909.5 946.8 957.9 995.7 1021.2 17578.8 19088.2 19295.7 19510.1 19855.5 899.7 955.1 968.1 1019.0 1027.2 9875.7 10409.3 10684.8 10734.4 10758.9 431491.0 445645.0 445728.0 446194.0 450185.0 85952.6 93224.5 93483.0 94109.0 96814.5 995.1 995.3 1031.1 1037.4 1040.4 16461.0 16701.7 17041.7 17423.7 18023.3 40718.8 42121.4 42337.4 43111.6 43125.8 58377.6 58545.1 58967.4 59390.0 60139.0 8249.7 8260.0 8381.4 8586.8 8590.3 12000.8 13074.7 13302.4 13330.0 13344.4 11490.7 11709.4 11795.8 11809.9 12022.4 11254.3 11278.9 11346.8 11389.8 11551.1 11675.4 11786.2 11955.8 11997.6 12309.6 8305.6 8501.8 8509.7 8671.4 8739.3 8767.7 10817.5 11510.3 11597.6 11910.7 07:32:42 INFO - PROCESS | 11238 | 07:32:42 INFO - PROCESS | 11238 | [#3] mootools.html Cycles:180 Average:5546.32 Median:4919.50 stddev:3164.48 (64.3%) stddev-sans-first:3157.38 07:32:42 INFO - PROCESS | 11238 | Values: 1314.4 1408.5 1451.0 1540.1 1719.7 7538.4 8225.9 8577.6 8588.7 8858.1 8491.2 8554.5 8609.3 8624.5 8636.1 2334.5 2357.6 2361.1 2361.5 2366.3 2307.7 2346.4 2346.6 2352.4 2352.8 2267.0 2278.9 2288.5 2325.3 2346.3 2208.5 2210.2 2210.8 2213.1 2237.0 2267.1 2271.1 2276.9 2281.1 2282.5 8317.4 8430.0 8518.4 8530.4 8562.5 4937.9 5108.9 5116.2 5116.9 5122.7 3139.1 3204.0 3240.7 3258.5 3268.5 1094.6 1113.3 1114.4 1118.9 1142.7 11875.5 11876.0 11988.0 12005.7 12015.2 4128.2 4314.5 4328.7 4429.6 4440.7 8828.6 8831.3 8835.4 8835.5 8836.2 4878.6 4894.2 4894.5 4898.9 4901.1 4048.4 4169.2 4408.9 4496.4 4609.1 3612.5 3637.9 3733.9 3745.8 3766.7 1069.1 1073.7 1078.0 1078.9 1100.5 943.6 946.9 950.7 953.7 981.4 5778.7 5788.6 5845.2 5908.9 5993.3 437.3 437.6 438.8 454.6 460.4 3836.2 4024.9 4057.0 4061.5 4157.7 9783.0 10382.6 10493.6 10549.2 10558.3 10318.8 10463.0 10465.2 10477.4 10486.2 7720.2 7900.2 7923.8 7963.5 7964.6 5618.1 5685.6 5754.4 5777.2 5942.6 9781.1 9816.8 9828.2 9891.3 9899.9 9731.7 9824.2 9887.6 9963.4 9975.6 6791.1 6973.3 7169.9 7203.5 7412.9 8377.4 8450.8 8458.9 8472.3 8481.8 7179.6 7266.0 7276.6 7439.7 7570.9 7229.5 7301.4 7376.4 7537.7 7595.9 6779.2 6902.0 6903.0 6905.0 6906.0 4854.8 4943.3 4959.3 5097.6 5107.7 4891.4 5064.7 5073.8 5139.8 5249.9 07:32:42 INFO - PROCESS | 11238 | 07:32:42 INFO - PROCESS | 11238 | [#4] prototype.html Cycles:180 Average:4629.80 Median:5216.69 stddev:1368.80 (26.2%) stddev-sans-first:1333.65 07:32:42 INFO - PROCESS | 11238 | Values: 308.2 317.9 322.9 328.9 328.9 5256.4 5311.0 5311.3 5313.7 5351.4 5212.9 5300.6 5307.9 5321.6 5344.2 4104.4 4180.2 4228.6 4232.8 4234.5 4175.6 4176.5 4177.6 4189.5 4191.4 4118.4 4128.7 4129.2 4136.5 4157.2 3938.6 4068.3 4137.6 4138.1 4178.2 3575.0 3645.5 3654.8 3657.6 3670.7 5146.7 5154.1 5159.3 5168.9 5174.2 4464.3 4477.5 4766.5 4770.5 4808.0 5512.0 5512.6 5517.2 5531.0 5578.3 4024.1 4034.7 4034.9 4037.4 4059.5 5567.4 5699.1 5778.8 5788.4 5805.0 4076.6 4079.6 4086.3 4091.5 4114.1 4147.2 4149.6 4152.3 4157.5 4170.8 4394.7 4668.1 4714.2 4744.5 4752.9 5493.3 5511.3 5527.0 5538.7 5553.2 1377.3 1396.3 1397.9 1400.7 1402.1 832.4 883.0 883.6 898.3 899.1 4645.9 4681.1 4693.2 4703.8 4715.8 4971.2 4973.0 5004.7 5098.7 5125.4 4709.8 4711.9 4817.2 4861.6 4987.2 2872.2 2930.6 2931.7 2953.9 3011.8 5859.6 5867.8 5887.0 5914.3 5932.4 5557.9 5835.8 5848.7 5866.6 5874.5 5854.3 5856.9 5866.5 5870.4 5886.9 5662.7 5665.5 5668.9 5669.4 5689.5 5386.0 5663.4 5674.3 5687.5 5695.7 5917.5 5925.9 5929.8 5931.8 5933.6 3974.3 3996.6 4008.0 4010.3 4011.3 5473.2 5690.7 5726.7 5729.1 5842.4 5588.9 5593.0 5603.3 5621.0 5622.7 5347.6 5581.6 5601.0 5617.7 5634.9 5527.4 5549.7 5554.9 5555.6 5556.4 5545.3 5576.3 5589.9 5622.9 5658.3 5220.4 5449.8 5455.0 5500.9 5517.3 07:32:42 INFO - PROCESS | 11238 | 07:32:42 INFO - PROCESS | 11238 | [#5] yui.html Cycles:180 Average:20645.50 Median:52110.61 stddev:31352.63 (60.2%) stddev-sans-first:31404.63 07:32:42 INFO - PROCESS | 11238 | Values: 648.3 668.2 668.3 695.0 737.5 12471.6 13075.9 13099.2 13354.3 13955.4 13692.5 13754.4 13790.7 13793.4 13811.2 661.6 784.8 807.3 811.4 812.6 670.1 788.4 802.5 813.5 819.9 857.1 860.7 860.7 861.0 861.5 686.5 848.0 852.7 853.3 853.5 515.1 605.1 617.0 619.4 620.1 12611.7 12621.4 12623.2 12634.3 12668.0 13221.7 13424.2 13851.4 13899.7 14256.0 10893.6 11049.2 11097.8 12566.2 12708.1 3733.6 3747.6 3748.6 3792.1 3825.3 78527.0 78674.6 79114.2 80379.2 80845.6 11083.4 11199.4 11360.8 11377.7 11388.7 19494.5 19511.0 19629.9 19651.6 19656.9 7385.3 7396.9 7457.3 7495.7 7495.9 4264.4 4344.7 4346.3 4422.8 4445.0 3273.5 3423.0 3425.1 3425.3 3426.8 589.8 594.5 601.1 602.2 603.9 532.7 623.3 713.6 734.9 782.8 9108.5 9461.3 9488.3 9581.8 9596.0 617.6 696.1 696.4 729.5 760.1 4326.8 4388.3 4388.5 4517.2 4606.9 115566.0 119508.0 123726.0 124379.0 128604.4 95902.5 96697.1 97186.5 98354.0 99406.0 89757.6 90838.5 91401.5 91581.5 91639.0 8394.9 8462.8 8507.5 8692.9 8713.1 51977.7 52243.5 52536.5 53184.7 53318.5 70325.0 78115.6 79640.6 79935.0 82648.6 10724.9 11462.5 11689.7 11771.5 11889.4 11604.6 11868.8 12071.8 12147.5 13005.1 11327.4 11392.7 11710.8 11796.9 11844.3 11259.8 11435.2 11748.8 11762.2 11897.9 11334.3 11460.7 11506.2 12004.4 12100.7 9154.6 9372.9 9481.6 9754.2 9757.8 9391.2 10349.9 10364.2 10442.3 10461.5 07:32:42 INFO - PROCESS | 11238 | -------- Summary: end -------- 07:32:42 INFO - PROCESS | 11238 | 07:32:43 INFO - TEST-INFO | 11238: exit 0 07:32:43 INFO - TEST-OK | dromaeo_css | took 1116795ms 07:32:43 INFO - TEST-START | kraken 07:32:43 INFO - operating with platform_type : linux_ 07:32:43 INFO - Initialising browser for kraken test... 07:32:43 INFO - TEST-INFO | started process 11554 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmzECsB/profile http://localhost:55622/getInfo.html) 07:32:47 INFO - PROCESS | 11554 | __metrics Screen width/height:1600/1200 07:32:47 INFO - PROCESS | 11554 | colorDepth:24 07:32:47 INFO - PROCESS | 11554 | Browser inner width/height: 1024/697 07:32:47 INFO - PROCESS | 11554 | __metrics 07:32:48 INFO - PROCESS | 11554 | 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" 07:32:48 INFO - PROCESS | 11554 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:32:49 INFO - PROCESS | 11554 | console.error: PushService: 07:32:49 INFO - PROCESS | 11554 | stateChangeProcessEnqueue: Error transitioning state 07:32:49 INFO - PROCESS | 11554 | UnknownError 07:32:49 INFO - TEST-INFO | 11554: exit 0 07:32:49 INFO - Browser initialized. 07:32:49 INFO - Running cycle 1/1 for kraken test... 07:32:49 INFO - TEST-INFO | started process 11638 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmzECsB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:01 INFO - PROCESS | 11638 | RSS: Main: 173522944 07:33:01 INFO - PROCESS | 11638 | 07:33:04 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55622/tests/kraken/driver.html?testName=audio-beat-detection) 07:33:05 INFO - PROCESS | 11638 | RSS: Main: 232497152 07:33:05 INFO - PROCESS | 11638 | 07:33:07 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55622/tests/kraken/driver.html?testName=audio-dft) 07:33:08 INFO - PROCESS | 11638 | RSS: Main: 216465408 07:33:08 INFO - PROCESS | 11638 | 07:33:11 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55622/tests/kraken/driver.html?testName=audio-fft) 07:33:12 INFO - PROCESS | 11638 | RSS: Main: 298569728 07:33:12 INFO - PROCESS | 11638 | 07:33:14 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55622/tests/kraken/driver.html?testName=audio-oscillator) 07:33:14 INFO - PROCESS | 11638 | RSS: Main: 207618048 07:33:14 INFO - PROCESS | 11638 | 07:33:17 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55622/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:33:17 INFO - PROCESS | 11638 | RSS: Main: 211976192 07:33:17 INFO - PROCESS | 11638 | 07:33:20 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55622/tests/kraken/driver.html?testName=imaging-darkroom) 07:33:21 INFO - PROCESS | 11638 | RSS: Main: 248631296 07:33:21 INFO - PROCESS | 11638 | 07:33:24 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55622/tests/kraken/driver.html?testName=imaging-desaturate) 07:33:25 INFO - PROCESS | 11638 | RSS: Main: 247771136 07:33:25 INFO - PROCESS | 11638 | 07:33:28 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55622/tests/kraken/driver.html?testName=json-parse-financial) 07:33:28 INFO - PROCESS | 11638 | RSS: Main: 247508992 07:33:28 INFO - PROCESS | 11638 | 07:33:30 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55622/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:33:30 INFO - PROCESS | 11638 | RSS: Main: 210161664 07:33:30 INFO - PROCESS | 11638 | 07:33:32 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55622/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:33:33 INFO - PROCESS | 11638 | RSS: Main: 218648576 07:33:33 INFO - PROCESS | 11638 | 07:33:35 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55622/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:33:35 INFO - PROCESS | 11638 | RSS: Main: 221806592 07:33:35 INFO - PROCESS | 11638 | 07:33:38 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55622/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:33:39 INFO - PROCESS | 11638 | RSS: Main: 222912512 07:33:39 INFO - PROCESS | 11638 | 07:33:41 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55622/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:33:42 INFO - PROCESS | 11638 | RSS: Main: 221032448 07:33:42 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | Cycle 1(1): loaded http://localhost:55622/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55622/tests/kraken/driver.html?testName=ai-astar) 07:33:44 INFO - PROCESS | 11638 | RSS: Main: 272228352 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | __start_tp_report 07:33:44 INFO - PROCESS | 11638 | _x_x_mozilla_page_load 07:33:44 INFO - PROCESS | 11638 | _x_x_mozilla_page_load_details 07:33:44 INFO - PROCESS | 11638 | |i|pagename|runs| 07:33:44 INFO - PROCESS | 11638 | |0;ai-astar;113;112;111;113;112;110;110;112;112;112 07:33:44 INFO - PROCESS | 11638 | |1;audio-beat-detection;153;119;119;158;126;124;122;119;158;118 07:33:44 INFO - PROCESS | 11638 | |2;audio-dft;162;162;162;162;163;162;163;163;162;163 07:33:44 INFO - PROCESS | 11638 | |3;audio-fft;76;77;75;111;76;76;76;78;97;75 07:33:44 INFO - PROCESS | 11638 | |4;audio-oscillator;83;83;110;82;83;82;109;81;82;112 07:33:44 INFO - PROCESS | 11638 | |5;imaging-gaussian-blur;117;117;118;118;117;117;117;117;118;117 07:33:44 INFO - PROCESS | 11638 | |6;imaging-darkroom;149;149;149;149;149;149;149;149;149;146 07:33:44 INFO - PROCESS | 11638 | |7;imaging-desaturate;95;96;96;97;95;96;97;96;96;95 07:33:44 INFO - PROCESS | 11638 | |8;json-parse-financial;50;50;51;50;52;52;51;52;51;51 07:33:44 INFO - PROCESS | 11638 | |9;json-stringify-tinderbox;60;60;60;60;60;60;60;60;60;60 07:33:44 INFO - PROCESS | 11638 | |10;stanford-crypto-aes;80;79;78;80;80;78;78;78;79;79 07:33:44 INFO - PROCESS | 11638 | |11;stanford-crypto-ccm;141;125;132;124;132;135;141;127;168;140 07:33:44 INFO - PROCESS | 11638 | |12;stanford-crypto-pbkdf2;166;164;166;165;164;161;164;164;169;162 07:33:44 INFO - PROCESS | 11638 | |13;stanford-crypto-sha256-iterative;69;65;66;65;64;69;65;64;64;63 07:33:44 INFO - PROCESS | 11638 | __end_tp_report 07:33:44 INFO - PROCESS | 11638 | __start_cc_report 07:33:44 INFO - PROCESS | 11638 | _x_x_mozilla_cycle_collect,1635 07:33:44 INFO - PROCESS | 11638 | __end_cc_report 07:33:44 INFO - PROCESS | 11638 | __startTimestamp1474036424287__endTimestamp 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | ------- Summary: start ------- 07:33:44 INFO - PROCESS | 11638 | Number of tests: 14 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.06 (0.9%) stddev-sans-first:1.01 07:33:44 INFO - PROCESS | 11638 | Values: 113.0 112.0 111.0 113.0 112.0 110.0 110.0 112.0 112.0 112.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:125.00 stddev:17.30 (13.8%) stddev-sans-first:16.53 07:33:44 INFO - PROCESS | 11638 | Values: 153.0 119.0 119.0 158.0 126.0 124.0 122.0 119.0 158.0 118.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 07:33:44 INFO - PROCESS | 11638 | Values: 162.0 162.0 162.0 162.0 163.0 162.0 163.0 163.0 162.0 163.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.00 stddev:12.24 (16.1%) stddev-sans-first:12.81 07:33:44 INFO - PROCESS | 11638 | Values: 76.0 77.0 75.0 111.0 76.0 76.0 76.0 78.0 97.0 75.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:82.00 stddev:13.58 (16.6%) stddev-sans-first:14.12 07:33:44 INFO - PROCESS | 11638 | Values: 83.0 83.0 110.0 82.0 83.0 82.0 109.0 81.0 82.0 112.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:33:44 INFO - PROCESS | 11638 | Values: 117.0 117.0 118.0 118.0 117.0 117.0 117.0 117.0 118.0 117.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.95 (0.6%) stddev-sans-first:1.00 07:33:44 INFO - PROCESS | 11638 | Values: 149.0 149.0 149.0 149.0 149.0 149.0 149.0 149.0 149.0 146.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 07:33:44 INFO - PROCESS | 11638 | Values: 95.0 96.0 96.0 97.0 95.0 96.0 97.0 96.0 96.0 95.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:0.82 (1.6%) stddev-sans-first:0.78 07:33:44 INFO - PROCESS | 11638 | Values: 50.0 50.0 51.0 50.0 52.0 52.0 51.0 52.0 51.0 51.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 07:33:44 INFO - PROCESS | 11638 | Values: 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 60.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:79.00 stddev:0.88 (1.1%) stddev-sans-first:0.83 07:33:44 INFO - PROCESS | 11638 | Values: 80.0 79.0 78.0 80.0 80.0 78.0 78.0 78.0 79.0 79.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#11] stanford-crypto-ccm Cycles:10 Average:136.50 Median:137.50 stddev:12.76 (9.3%) stddev-sans-first:13.44 07:33:44 INFO - PROCESS | 11638 | Values: 141.0 125.0 132.0 124.0 132.0 135.0 141.0 127.0 168.0 140.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:164.50 stddev:2.22 (1.4%) stddev-sans-first:2.29 07:33:44 INFO - PROCESS | 11638 | Values: 166.0 164.0 166.0 165.0 164.0 161.0 164.0 164.0 169.0 162.0 07:33:44 INFO - PROCESS | 11638 | 07:33:44 INFO - PROCESS | 11638 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.00 stddev:2.07 (3.2%) stddev-sans-first:1.73 07:33:44 INFO - PROCESS | 11638 | Values: 69.0 65.0 66.0 65.0 64.0 69.0 65.0 64.0 64.0 63.0 07:33:44 INFO - PROCESS | 11638 | -------- Summary: end -------- 07:33:44 INFO - PROCESS | 11638 | 07:33:45 INFO - TEST-INFO | 11638: exit 0 07:33:45 INFO - TEST-OK | kraken | took 62186ms 07:33:45 INFO - SUITE-END | took 1178s 07:33:45 INFO - Completed test suite (00:19:39) 07:33:45 INFO - javascript benchmark 07:33:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2973.2566858285427, 3008.5186370180777, 3008.8345141017244, 3028.3034901972533, 3056.9541456878137, 5117.641765076446, 5130.38435387754, 5209.8697532561655, 5223.843284701456, 5236.397814251362, 5143.7685304161305, 5196.350456192976, 5221.034108689895, 5273.18265668821, 5283.656562323446, 4729.78715957781, 4747.928781068286, 4783.665143439961, 4814.205656066745, 4830.637702172334, 4858.659893807435, 4874.2201247800185, 4896.436909755388, 4901.436334821487, 4958.3306253655655, 4647.23320652092, 4774.212254977924, 4792.568668819823, 4819.831305904294, 4891.461939186686, 4651.5813576778255, 4652.720836749772, 4656.99874526975, 4667.929981050287, 4746.38297021385, 4627.329037289608, 4636.721796692209, 4639.095376401603, 4648.814047438098, 4692.061587682443, 5064.772085256172, 5107.131787596118, 5109.310243117169, 5122.462141475129, 5146.871328216787, 5341.65279256847, 5403.8108666196495, 5488.17677348397, 5556.305461817273, 5570.0, 5513.283273174502, 5531.446855314477, 5594.916076258859, 5638.097904335327, 5657.8585535361535, 5190.92213616796, 5194.220866869962, 5200.583953283766, 5205.55752761012, 5255.947440525622, 5426.131818908955, 5493.642913210629, 5555.444455554412, 5648.124540696199, 5655.632383895034, 5405.729713514387, 5483.177523371526, 5487.2317875497465, 5495.203195536705, 5543.057680194296, 5499.120140777456, 5538.889222215614, 5541.9168712469345, 5549.72251387429, 5553.694546799884, 5406.324209473816, 5416.620836541404, 5499.697516636624, 5568.415316391802, 5624.634398764068, 5430.294061771944, 5433.483819037185, 5476.616636835383, 5559.305086864142, 5563.638363506359, 4263.978680106579, 4269.210196113729, 4322.200392927319, 4322.8703138905885, 4369.69412141147, 3837.136644255076, 3861.2084522672785, 3897.512810898638, 3899.1421887184774, 3924.5486769021927, 5221.060209162311, 5238.528532432099, 5278.788848446096, 5286.20706893969, 5286.36563612369, 5536.695481748462, 5570.888582228333, 5581.441855814467, 5583.748731307101, 5585.218069470277, 5158.92261616076, 5278.076336641149, 5285.788568457296, 5299.125644268653, 5347.064263753828, 4809.855704328876, 4836.830710925101, 4885.5847252983185, 4891.706497610155, 4943.604511639061, 5748.770049198069, 5748.827535173951, 5761.135829625506, 5762.250907382014, 5763.711814409179, 5559.360673522435, 5565.1374037024325, 5565.749541270571, 5571.916421253765, 5572.637778544382, 5575.303087114111, 5611.971940140274, 5619.859503512282, 5629.7748090077575, 5701.201831743476, 5377.784888604569, 5390.892182156414, 5394.029074766423, 5394.244805727123, 5453.509184173444, 5420.4850539198715, 5428.348598168245, 5470.0, 5482.479164479368, 5543.223948647274, 5315.548178404724, 5335.253064571041, 5375.56995440356, 5377.838664840061, 5380.0, 5417.160340147283, 5468.56251499887, 5473.042217611982, 5476.041692703681, 5481.150421684645, 5344.305240318734, 5344.75948582307, 5345.759440825094, 5353.0364534382625, 5356.0626890294825, 5334.199870019528, 5440.211169380497, 5459.617826752248, 5462.3445186577865, 5521.503064724194, 5274.920876186783, 5314.654547454403, 5319.813806516753, 5320.361556613231, 5380.569554435558], "value": 5118.049305389694, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.9312531444425, 596.8627215740377, 599.1581827532317, 602.7167231401243, 611.9143319935208, 32039.51940720891, 44182.779086104565, 44827.32759008609, 44842.32736508955, 44874.32688509667, 45706.0, 45795.31307030397, 45966.0, 46025.769871150595, 46045.76977115118, 8098.595070246493, 8944.016158222606, 9083.137101975335, 9092.27261819053, 9099.135582119721, 8190.754277371659, 10560.94719526405, 10826.783464330709, 10842.403667798308, 11131.610393636225, 11035.117190624771, 11096.278741881792, 11097.83353249702, 11098.778024439507, 11099.72250693731, 10857.67426977192, 10880.292780969212, 10909.945450272699, 10923.890761092367, 10933.0, 10537.0, 10623.362598244054, 10634.46827658614, 10683.30558513694, 10693.358398496115, 42232.0, 42538.78730606358, 42555.7872210637, 42568.78715606433, 42872.0, 3340.450204298992, 3410.1474631342167, 3483.4672743992564, 3653.963460365389, 3754.6808521275725, 32314.192145196324, 32510.0, 32708.182295442566, 32737.181570460692, 34378.14054648654, 4016.4176194451643, 4030.3349947258666, 4079.1841631673783, 4128.731632443912, 4223.028703398236, 319408.0, 322446.0, 322537.0, 322871.0, 323275.0, 56748.0, 56927.43072569221, 56961.715191424606, 56976.0, 56980.43019569751, 36085.8195709025, 36796.44805327922, 36877.446838297445, 38774.95925529663, 60852.391476084675, 15204.771928421083, 15233.923830380778, 15320.923395382952, 15346.923265383602, 15362.615934601725, 11046.447677616086, 11324.37715925632, 11889.405529723479, 12245.265284082983, 12331.260124392566, 18654.906725466284, 18892.527686807665, 18999.905000475184, 19019.90490047541, 19086.90456547736, 7121.928780712126, 7144.607046612382, 7205.711771529185, 7283.963580182065, 7284.235155308722, 890.888638920135, 984.921206303494, 985.1822986920838, 1063.2079101069598, 1111.5387114347454, 31725.206869827976, 31834.0, 32016.199595009846, 32046.679533204835, 32619.51070733941, 1071.1377341240377, 1076.4886678827495, 1082.1018554599664, 1136.852209212797, 1143.8398624192619, 15590.064596124268, 16500.917495412446, 16572.75140872888, 16609.584760380847, 16961.575960600836, 150225.0, 150345.0, 150542.0, 151366.0, 151461.24269378584, 58030.129548056815, 58371.41628583745, 58578.414215857294, 58648.41351586515, 58648.706756465945, 1207.704112492445, 1227.4967263422134, 1306.2097431054076, 1338.5716570697284, 1379.2345248387041, 28823.0, 32934.670653293164, 33180.33639327248, 33552.49671254884, 33723.09896001734, 49038.50961490339, 49499.752501237264, 49524.75237623789, 49573.25640115329, 49602.503974959785, 358310.0, 360512.0, 361050.0, 361531.0, 361623.0, 28547.571786423636, 44192.12751171164, 44249.55750442454, 45019.549804501534, 45921.31118033299, 45891.31163032624, 46191.0, 46491.53508464872, 49608.50391496183, 49971.750141250515, 40293.0, 40960.38559421671, 41128.794356028026, 41562.0, 43255.0, 41644.375334369404, 42011.0, 42802.78598606987, 42872.571274286856, 43215.78392108019, 41687.791561042, 42803.57196427996, 43104.78447607742, 43110.56889431066, 43136.13727725491, 34770.0, 36909.726359267515, 37179.0, 37759.433608496445, 38086.23827523489, 28155.859220703765, 28373.0, 28392.0, 28698.856505718173, 28704.712952870203], "value": 19566.41162809655, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.5492247384583, 364.1115677746297, 388.3785942492013, 393.32741012867996, 398.8903051660793, 14378.352974116164, 18191.54521136974, 18484.35304764332, 18632.534186645342, 19621.901890490528, 18285.268589256415, 18622.348217812378, 18766.812331876677, 18818.905905470452, 18898.14958326875, 5424.5117939385345, 5666.235058267143, 5676.545876329905, 5678.886422271573, 5680.0, 5481.095619222843, 5503.972480137593, 5560.6941618210985, 5564.054110801153, 5638.154276858483, 5546.362168350651, 5563.193336966137, 5563.749631266603, 5565.499105080542, 5569.888602227953, 5361.919571206435, 5486.588505862076, 5502.504774570308, 5536.944630553703, 5552.333719953611, 4961.578265847407, 4965.577925876305, 4971.676841005323, 4975.62682798788, 4980.05378977993, 22952.54094918109, 23038.65442018371, 23095.653565196535, 23136.190233341753, 23421.0, 2098.0, 2210.502636906699, 2395.4370722880108, 2515.408878913454, 2672.3452754075374, 9773.12041916231, 9999.300048996573, 10175.796484070277, 10191.745206369826, 10421.0, 1765.3997640802188, 1773.9556511087196, 1870.9438716838517, 1872.3353209610527, 1891.914863831131, 72808.07878818167, 82123.0, 85433.0, 85501.14498854995, 87778.56110719405, 10215.540300686487, 10287.588496460208, 10543.683689489328, 10642.680719578426, 10715.624953126604, 14302.713945721027, 14377.6405589859, 14443.855561444461, 14887.255637218095, 14912.776308355384, 7153.7138514459875, 7481.438892083007, 7600.1639819619795, 7681.116671582728, 7823.452358334862, 7193.784186474414, 7237.601931893796, 7316.926830731731, 7356.852862942711, 7439.279236653184, 4403.6660569898, 4447.266201076851, 4489.9551004490195, 4569.154706379264, 4720.103180395714, 1816.2644129127725, 1854.1378259109308, 1888.2447021191358, 1934.9032548372525, 1960.2551030608277, 909.5452273863068, 946.7822400847843, 957.9089986451276, 995.7012896131132, 1021.2085633633993, 17578.761203425267, 19088.236470541022, 19295.710564341545, 19510.122044508033, 19855.503612409517, 899.7300809757178, 955.1069749783974, 968.1045033344128, 1019.0421004255976, 1027.1525991057408, 9875.654352097643, 10409.27135100551, 10684.839727404094, 10734.409607471664, 10758.94620526908, 431491.0, 445645.0, 445728.0, 446194.0, 450185.0, 85952.57023714841, 93224.53387733018, 93483.0, 94109.0, 96814.51592741991, 995.1392045880406, 995.3082607587802, 1031.0617338222182, 1037.3983089807894, 1040.4485622620023, 16461.0, 16701.665966680357, 17041.744373834652, 17423.738643920606, 18023.2790688371, 40718.796406017784, 42121.36817947795, 42337.364939525316, 43111.56888431076, 43125.78437107794, 58377.62654612357, 58545.12182317354, 58967.41032589791, 59390.0, 60139.0, 8249.670013199404, 8260.008798944165, 8381.413301069111, 8586.840776495093, 8590.312774977861, 12000.75998480043, 13074.738505230032, 13302.401391937192, 13330.0, 13344.399502022627, 11490.655280341603, 11709.414529273332, 11795.764084718428, 11809.881901180877, 12022.415180736183, 11254.324740515594, 11278.943605281922, 11346.829797552879, 11389.772204555697, 11551.133664974992, 11675.3578553177, 11786.233894796811, 11955.820662690241, 11997.64007079789, 12309.56916507918, 8305.626246819029, 8501.787455313666, 8509.659613615386, 8671.4363566368, 8739.257163141205, 8767.736967890973, 10817.459127043774, 11510.251833630788, 11597.594084207012, 11910.736959977576], "value": 8973.83357093899, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.3756715560105, 1408.5422237772723, 1451.0205611212432, 1540.1144342003345, 1719.7248440249555, 7538.434617403696, 8225.87661185082, 8577.614007369668, 8588.742337729871, 8858.069902660209, 8491.23578877901, 8554.52950087745, 8609.26821220197, 8624.525651089187, 8636.093210212932, 2334.5447637710695, 2357.6463530470483, 2361.055577768889, 2361.504084142332, 2366.3374255208482, 2307.6769252304607, 2346.3899386159637, 2346.5541547105995, 2352.3766201956496, 2352.764723527651, 2266.9659955100688, 2278.8860556972168, 2288.519410923708, 2325.2791634593245, 2346.272655476799, 2208.4589275627495, 2210.1932794530044, 2210.823134149264, 2213.1036930043438, 2236.966445503319, 2267.1044937249694, 2271.068861766668, 2276.8633881967135, 2281.0989659084566, 2282.4864405508793, 8317.41778075535, 8430.0, 8518.446300990416, 8530.445521041112, 8562.529060901648, 4937.925931111036, 5108.923366149511, 5116.232565115225, 5116.872078198075, 5122.718250496221, 3139.136737397211, 3203.983980080108, 3240.7245384142375, 3258.4949332853444, 3268.4607039838397, 1094.5840580579409, 1113.3320007995137, 1114.3536748685742, 1118.8992990630882, 1142.6971852459105, 11875.465604047838, 11876.0499160067, 11988.0, 12005.699857503545, 12015.21901076436, 4128.174365126986, 4314.460692413449, 4328.653707703439, 4429.645628349725, 4440.666949978765, 8828.646854125891, 8831.293496520262, 8835.381523293308, 8835.469871807583, 8836.204741573289, 4878.585320247818, 4894.216925291937, 4894.535019173173, 4898.877528061756, 4901.068796928572, 4048.352263637804, 4169.187008533307, 4408.9118217636105, 4496.4154659894, 4609.078184363141, 3612.4942508048894, 3637.909052273714, 3733.8506459741848, 3745.793981331053, 3766.7363284570365, 1069.1072954083427, 1073.688630297205, 1078.0, 1078.9298695584762, 1100.4552746390589, 943.613118621362, 946.9100435458621, 950.695777351241, 953.6566835939057, 981.3572110267786, 5778.682172480554, 5788.565857560616, 5845.181674565564, 5908.940910590925, 5993.340732519419, 437.2850389613006, 437.5974103824489, 438.7893810970752, 454.60222305482705, 460.415272603798, 3836.1943991761486, 4024.939625905614, 4057.0, 4061.5126184858004, 4157.708960372805, 9783.0, 10382.584696612052, 10493.632722854663, 10549.156067514581, 10558.260921735558, 10318.845217321747, 10463.0, 10465.162786977024, 10477.37135771856, 10486.161107111413, 7720.15078341382, 7900.170482099412, 7923.841523169504, 7963.48237364581, 7964.561949092739, 5618.111172785638, 5685.602007859576, 5754.424557544212, 5777.191193232867, 5942.64344139353, 9781.119699227103, 9816.754581135243, 9828.16460600857, 9891.258155638212, 9899.851502227328, 9731.708048758548, 9824.164945979672, 9887.55506002246, 9963.352382095141, 9975.60097596088, 6791.083203767428, 6973.267806880204, 7169.928300717134, 7203.495755297079, 7412.851742965218, 8377.413581049268, 8450.788730281547, 8458.873116903374, 8472.279856212292, 8481.787955301166, 7179.569225846466, 7266.0, 7276.563406195433, 7439.739609113655, 7570.886436703564, 7229.49393542468, 7301.379382752525, 7376.37300829414, 7537.660805263667, 7595.88606170919, 6779.15260592426, 6901.999210114605, 6903.0, 6904.965475172793, 6906.0, 4854.83008094715, 4943.30793688891, 4959.280904268933, 5097.617678674188, 5107.744612769272, 4891.364122664031, 5064.670796398223, 5073.797048118034, 5139.820106296262, 5249.921251181159], "value": 4368.785754659803, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.2232773411004, 317.9062176657886, 322.9499427588724, 328.85530366638676, 328.9210589458531, 5256.369235691717, 5311.0, 5311.256424100629, 5313.698143954736, 5351.357837059559, 5212.947870521293, 5300.628955973083, 5307.920381194285, 5321.627486075967, 5344.22508736233, 4104.445899803518, 4180.184863951538, 4228.577142285762, 4232.830686772525, 4234.470691163605, 4175.561566035568, 4176.498820141587, 4177.582241775828, 4189.539150693421, 4191.413202151702, 4118.423420721087, 4128.669706423494, 4129.174165166964, 4136.462259906208, 4157.210130075291, 3938.6061393860673, 4068.2880495913096, 4137.6069273419125, 4138.1103062841485, 4178.185253875496, 3574.9811303778297, 3645.471406646048, 3654.780713157219, 3657.6159503252043, 3670.724695647837, 5146.691198528063, 5154.149565321717, 5159.277701121846, 5168.896622067575, 5174.249733788617, 4464.308032255006, 4477.507474177838, 4766.499517550642, 4770.475247722748, 4807.966287248259, 5512.0, 5512.641678290938, 5517.200005999148, 5530.972345138289, 5578.2748242728185, 4024.1348110156164, 4034.7175697701173, 4034.919301613951, 4037.354023356278, 4059.512858457004, 5567.4432556744405, 5699.145128230799, 5778.8266352009505, 5788.392218817048, 5805.042168042268, 4076.5719599442223, 4079.63283304504, 4086.346184610448, 4091.5499295077516, 4114.0948991221885, 4147.170565886774, 4149.585041495826, 4152.314868046798, 4157.521884983205, 4170.833166673299, 4394.670399720034, 4668.089722504079, 4714.15145273854, 4744.525547445228, 4752.884966190012, 5493.313335833021, 5511.283533140746, 5526.972365138229, 5538.723063846792, 5553.194786755978, 1377.2631642071444, 1396.2739375524873, 1397.9440822366992, 1400.6848459096623, 1402.0956483068364, 832.3549249331817, 883.0022075055147, 883.6067949762422, 898.3038145437208, 899.1458114790975, 4645.9303110453375, 4681.063787242497, 4693.202155633482, 4703.7883295251795, 4715.811367545329, 4971.229459433794, 4972.975135124301, 5004.749762511859, 5098.694078355311, 5125.436202017775, 4709.811607535728, 4711.858644240679, 4817.229243321051, 4861.586765124934, 4987.226979818134, 2872.224499385154, 2930.633670791151, 2931.724699755613, 2953.9409211816073, 3011.789174757746, 5859.560532960045, 5867.823965281048, 5886.970565147147, 5914.319853216849, 5932.377100404396, 5557.944420555824, 5835.824925252249, 5848.678322692293, 5866.6186697864505, 5874.50066744323, 5854.297484301911, 5856.8535786604825, 5866.530677545786, 5870.354261031369, 5886.8822623547285, 5662.716864156693, 5665.461781130786, 5668.858278543069, 5669.4047125051275, 5689.54483641316, 5385.973070134624, 5663.3770285267765, 5674.319081710221, 5687.5449964001955, 5695.658260504383, 5917.526597872074, 5925.911111333419, 5929.762809487745, 5931.822045338647, 5933.554983376351, 3974.2647610192207, 3996.6403023728003, 4007.979960100181, 4010.27814993304, 4011.2980228460488, 5473.233747275464, 5690.715464226855, 5726.74229659658, 5729.140628905531, 5842.38654941242, 5588.904160939647, 5592.972035139798, 5603.327600687943, 5620.988222120058, 5622.718864056699, 5347.625666203328, 5581.553475721851, 5601.0, 5617.691026993554, 5634.943650563442, 5527.44725527444, 5549.722513874371, 5554.861128471821, 5555.638883472561, 5556.416576259435, 5545.306836645419, 5576.275084239023, 5589.860253493695, 5622.943770562406, 5658.292713410824, 5220.425753167225, 5449.782008719607, 5454.972725136349, 5500.944990550043, 5517.337919449692], "value": 4186.952103222498, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.3160265919453, 668.1881513960537, 668.2782594797619, 694.9887913086461, 737.494816051005, 12471.625851224466, 13075.934620326896, 13099.214047157177, 13354.26551539665, 13955.441782328695, 13692.452301907937, 13754.381052852621, 13790.724185516283, 13793.448262069505, 13811.240381778998, 661.6394065234459, 784.8351846112323, 807.3460496997451, 811.3995643224001, 812.553095797312, 670.1121014655577, 788.3850596534712, 802.4904185841974, 813.4509206285763, 819.9139090395512, 857.1428571428571, 860.6729442811722, 860.7417774667606, 861.0227391910196, 861.5003298087125, 686.5228666077079, 847.9830403391928, 852.6674596907211, 853.2747164909839, 853.5007020892754, 515.1345739158213, 605.0742364182807, 617.0343412559375, 619.3899009475663, 620.1163342237294, 12611.747765044647, 12621.368931553385, 12623.242605443702, 12634.305113218768, 12668.0, 13221.669458263621, 13424.173101705259, 13851.376688049062, 13899.722005559832, 14256.0, 10893.618723344724, 11049.17131215162, 11097.83353249702, 12566.246025238515, 12708.110432269745, 3733.5893051764283, 3747.5877653458097, 3748.5689145748315, 3792.051987003249, 3825.2540754552874, 78527.0, 78674.6066269665, 79114.20885791183, 80379.19620803776, 80845.59577202135, 11083.390413527333, 11199.384033878216, 11360.772784544428, 11377.715557110972, 11388.715282118013, 19494.5126371839, 19511.0, 19629.901850490656, 19651.606967860847, 19656.90171549133, 7385.29839665231, 7396.926030739732, 7457.2542745724995, 7495.66269517873, 7495.887561686579, 4264.360345948133, 4344.652427805768, 4346.304591265382, 4422.778861056934, 4445.022095139064, 3273.459879119966, 3422.9657703422645, 3425.0923505270625, 3425.3149370125575, 3426.811525366079, 589.755251570602, 594.5451729426992, 601.1403692719454, 602.2411761180856, 603.9154518367432, 532.6564365983919, 623.2583225961092, 713.5682911838263, 734.8603765284686, 782.792559971608, 9108.49903255314, 9461.33770636049, 9488.28837837165, 9581.808363832684, 9596.0, 617.555360140698, 696.1437431958719, 696.4150113904344, 729.529453502488, 760.1182628151317, 4326.848560300375, 4388.253996820548, 4388.495323037828, 4517.186906356887, 4606.88482787933, 115566.0, 119508.0, 123726.0, 124379.0, 128604.3569782145, 95902.52048739712, 96697.13211471462, 97186.5140674292, 98354.0, 99406.0, 89757.55121224483, 90838.54580727054, 91401.54299228461, 91581.5420922891, 91639.0, 8394.916050839414, 8462.83074338522, 8507.532085735344, 8692.869606955774, 8713.128687131237, 51977.7401112992, 52243.47756522386, 52536.47463525316, 53184.73407632937, 53318.466815332904, 70325.0, 78115.60942195253, 79640.60179699064, 79935.0, 82648.58675706583, 10724.89275107239, 11462.484188211383, 11689.707757306136, 11771.529138834694, 11889.405529723652, 11604.593839215588, 11868.821967670665, 12071.758564828478, 12147.453364598437, 13005.089643724848, 11327.376994265394, 11392.658220253406, 11710.765784684429, 11796.94101529487, 11844.335024619391, 11259.7748045037, 11435.199536032398, 11748.765024699629, 11762.176647634584, 11897.881021190024, 11334.319940803578, 11460.713482162679, 11506.194566380607, 12004.39978001114, 12100.697482563006, 9154.588043537924, 9372.859407109036, 9481.620735170516, 9754.219662426847, 9757.756056098655, 9391.20174785151, 10349.9482502587, 10364.170866330525, 10442.269041167348, 10461.529231184768], "value": 7106.50519808595, "unit": "score"}], "name": "dromaeo_css", "value": 6991.8576724761715, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 111.0, 113.0, 112.0, 110.0, 110.0, 112.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 119.0, 158.0, 126.0, 124.0, 122.0, 119.0, 158.0, 118.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 163.0, 162.0, 163.0, 163.0, 162.0, 163.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 111.0, 76.0, 76.0, 76.0, 78.0, 97.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 110.0, 82.0, 83.0, 82.0, 109.0, 81.0, 82.0, 112.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 149.0, 146.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 97.0, 95.0, 96.0, 97.0, 96.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 52.0, 52.0, 51.0, 52.0, 51.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 78.0, 80.0, 80.0, 78.0, 78.0, 78.0, 79.0, 79.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 125.0, 132.0, 124.0, 132.0, 135.0, 141.0, 127.0, 168.0, 140.0], "value": 136.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 166.0, 165.0, 164.0, 161.0, 164.0, 164.0, 169.0, 162.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 66.0, 65.0, 64.0, 69.0, 65.0, 64.0, 64.0, 63.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1496.3000000000002, "alertThreshold": 2.0}]} 07:33:46 INFO - Return code: 0 07:33:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:33:46 INFO - # TBPL SUCCESS # 07:33:46 INFO - Running post-action listener: _resource_record_post_action 07:33:46 INFO - [mozharness: 2016-09-16 14:33:46.076837Z] Finished run-tests step (success) 07:33:46 INFO - Running post-run listener: _resource_record_post_run 07:33:46 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395550720; Read time: 20; Write time: 383524 07:33:46 INFO - TinderboxPrint: CPU usage
13.1% 07:33:46 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 07:33:46 INFO - TinderboxPrint: I/O write bytes / time
395,550,720 / 383,524 07:33:46 INFO - TinderboxPrint: CPU idle
8,301.0 (86.9%) 07:33:46 INFO - TinderboxPrint: CPU user
1,193.2 (12.5%) 07:33:46 INFO - TinderboxPrint: Swap in / out
0 / 0 07:33:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 102879232; Read time: 0; Write time: 87280 07:33:46 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 292671488; Read time: 0; Write time: 296244 07:33:46 INFO - Running post-run listener: _upload_blobber_files 07:33:46 INFO - Blob upload gear active. 07:33:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:33:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:33:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:33:47 INFO - (blobuploader) - INFO - Open directory for files ... 07:33:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:33:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:33:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:48 INFO - (blobuploader) - INFO - Done attempting. 07:33:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:33:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:33:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:51 INFO - (blobuploader) - INFO - Done attempting. 07:33:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:33:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:33:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:51 INFO - (blobuploader) - INFO - Done attempting. 07:33:51 INFO - (blobuploader) - INFO - Iteration through files over. 07:33:51 INFO - Return code: 0 07:33:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:33:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:33:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27864544fcc0920ca8c4f9ca96efac4336e39748feadac23a4a80fcc1ca8ec4c0c547e0656011af0ee5260640b79589236df6a8349c10698ebbb97bf4028aeea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46840591e8f6fd53b983a7b0a802673ef80ca16e172e1a0669bf705a5fb32be655823ca3fa4d383369db47b3cccd51833f824ea9629e8a78a47a5d2ec7b35304", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a550e6a305e1c0648260314f12ea17c54736a69f50384683b421466c1635be33115c744f04a5d02aa8aa04e6b7c0dab78a7455ef42f0983d5fd284359d90476"} 07:33:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:33:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:33:51 INFO - Contents: 07:33:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27864544fcc0920ca8c4f9ca96efac4336e39748feadac23a4a80fcc1ca8ec4c0c547e0656011af0ee5260640b79589236df6a8349c10698ebbb97bf4028aeea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46840591e8f6fd53b983a7b0a802673ef80ca16e172e1a0669bf705a5fb32be655823ca3fa4d383369db47b3cccd51833f824ea9629e8a78a47a5d2ec7b35304", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a550e6a305e1c0648260314f12ea17c54736a69f50384683b421466c1635be33115c744f04a5d02aa8aa04e6b7c0dab78a7455ef42f0983d5fd284359d90476"} 07:33:51 INFO - Running post-run listener: copy_logs_to_upload_dir 07:33:51 INFO - Copying logs to upload dir... 07:33:51 INFO - mkdir: /builds/slave/test/build/upload/logs 07:33:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.324630 ========= master_lag: 0.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-09-16 07:33:51.138935) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 07:33:51.143957) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27864544fcc0920ca8c4f9ca96efac4336e39748feadac23a4a80fcc1ca8ec4c0c547e0656011af0ee5260640b79589236df6a8349c10698ebbb97bf4028aeea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46840591e8f6fd53b983a7b0a802673ef80ca16e172e1a0669bf705a5fb32be655823ca3fa4d383369db47b3cccd51833f824ea9629e8a78a47a5d2ec7b35304", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a550e6a305e1c0648260314f12ea17c54736a69f50384683b421466c1635be33115c744f04a5d02aa8aa04e6b7c0dab78a7455ef42f0983d5fd284359d90476"} build_url:https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011279 build_url: 'https://queue.taskcluster.net/v1/task/EtO5pC2DTsy4kJSrSjVBZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27864544fcc0920ca8c4f9ca96efac4336e39748feadac23a4a80fcc1ca8ec4c0c547e0656011af0ee5260640b79589236df6a8349c10698ebbb97bf4028aeea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46840591e8f6fd53b983a7b0a802673ef80ca16e172e1a0669bf705a5fb32be655823ca3fa4d383369db47b3cccd51833f824ea9629e8a78a47a5d2ec7b35304", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a550e6a305e1c0648260314f12ea17c54736a69f50384683b421466c1635be33115c744f04a5d02aa8aa04e6b7c0dab78a7455ef42f0983d5fd284359d90476"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-16 07:33:51.172391) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 07:33:51.172763) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1474033056.134280-1446545924 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-16 07:33:51.222521) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 07:33:51.225914) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-16 07:33:51.226336) ========= ========= Total master_lag: 0.37 =========