builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1474306741.44 results: success (0) buildid: 20160919092455 builduid: 71b65333b9b9421e8271ffcd2c525343 revision: e0b4bad921cd807f96b911ffb6339bf67c913194 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.444880) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.445468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.445878) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.460660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.460949) ========= 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=826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 10:39:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2016-09-19 10:39:01 (46.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104418 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.578994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.579298) ========= 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=826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014870 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.628389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:01.628735) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e0b4bad921cd807f96b911ffb6339bf67c913194 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev e0b4bad921cd807f96b911ffb6339bf67c913194 --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=826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 10:39:01,738 truncating revision to first 12 chars 2016-09-19 10:39:01,738 Setting DEBUG logging. 2016-09-19 10:39:01,738 attempt 1/10 2016-09-19 10:39:01,738 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e0b4bad921cd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 10:39:02,058 unpacking tar archive at: autoland-e0b4bad921cd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.662692 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:02.304840) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:02.306239) ========= script_repo_revision: e0b4bad921cd807f96b911ffb6339bf67c913194 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:02.306660) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:02.306937) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 10:39:02.317103) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-19 10:39:02.317500) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:39:02 INFO - MultiFileLogger online at 20160919 10:39:02 in /builds/slave/test 10:39:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 10:39:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:39:02 INFO - {'append_to_log': False, 10:39:02 INFO - 'base_work_dir': '/builds/slave/test', 10:39:02 INFO - 'blob_upload_branch': 'Autoland', 10:39:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:39:02 INFO - 'branch': 'Autoland', 10:39:02 INFO - 'buildbot_json_path': 'buildprops.json', 10:39:02 INFO - 'config_files': ('talos/linux_config.py',), 10:39:02 INFO - 'default_actions': ('clobber', 10:39:02 INFO - 'read-buildbot-config', 10:39:02 INFO - 'download-and-extract', 10:39:02 INFO - 'populate-webroot', 10:39:02 INFO - 'create-virtualenv', 10:39:02 INFO - 'install', 10:39:02 INFO - 'run-tests'), 10:39:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:39:02 INFO - 'download_minidump_stackwalk': True, 10:39:02 INFO - 'download_symbols': 'ondemand', 10:39:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:39:02 INFO - 'tooltool.py': '/tools/tooltool.py', 10:39:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:39:02 INFO - '/tools/misc-python/virtualenv.py')}, 10:39:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:39:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:39:02 INFO - 'installer_path': 'installer.exe', 10:39:02 INFO - 'log_level': 'info', 10:39:02 INFO - 'log_name': 'talos', 10:39:02 INFO - 'log_to_console': True, 10:39:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:39:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:39:02 INFO - 'opt_config_files': (), 10:39:02 INFO - 'pip_index': False, 10:39:02 INFO - 'sps_profile': False, 10:39:02 INFO - 'sps_profile_interval': 0, 10:39:02 INFO - 'suite': 'dromaeojs', 10:39:02 INFO - 'system_bits': '32', 10:39:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:39:02 INFO - 'title': 'talos-linux64-ix-052', 10:39:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:39:02 INFO - 'use_talos_json': True, 10:39:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:39:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:39:02 INFO - 'work_dir': 'build'} 10:39:02 INFO - [mozharness: 2016-09-19 17:39:02.522364Z] Running clobber step. 10:39:02 INFO - Running pre-action listener: _resource_record_pre_action 10:39:02 INFO - Running main action method: clobber 10:39:02 INFO - rmtree: /builds/slave/test/build 10:39:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:39:02 INFO - Running post-action listener: _resource_record_post_action 10:39:02 INFO - [mozharness: 2016-09-19 17:39:02.974547Z] Finished clobber step (success) 10:39:02 INFO - [mozharness: 2016-09-19 17:39:02.974646Z] Running read-buildbot-config step. 10:39:02 INFO - Running pre-action listener: _resource_record_pre_action 10:39:02 INFO - Running main action method: read_buildbot_config 10:39:02 INFO - Using buildbot properties: 10:39:02 INFO - { 10:39:02 INFO - "project": "", 10:39:02 INFO - "product": "firefox", 10:39:02 INFO - "script_repo_revision": "production", 10:39:02 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 10:39:02 INFO - "repository": "", 10:39:02 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 10:39:02 INFO - "buildid": "20160919092455", 10:39:02 INFO - "stage_platform": "linux64-pgo", 10:39:02 INFO - "basedir": "/builds/slave/test", 10:39:02 INFO - "buildnumber": 215, 10:39:02 INFO - "platform": "ubuntu64_hw", 10:39:02 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:39:02 INFO - "slavebuilddir": "test", 10:39:02 INFO - "branch": "autoland", 10:39:02 INFO - "revision": "e0b4bad921cd807f96b911ffb6339bf67c913194", 10:39:02 INFO - "slavename": "talos-linux64-ix-052", 10:39:02 INFO - "builduid": "71b65333b9b9421e8271ffcd2c525343", 10:39:02 INFO - "repo_path": "integration/autoland" 10:39:02 INFO - } 10:39:02 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 10:39:02 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 10:39:02 INFO - Running post-action listener: _resource_record_post_action 10:39:02 INFO - [mozharness: 2016-09-19 17:39:02.977383Z] Finished read-buildbot-config step (success) 10:39:02 INFO - [mozharness: 2016-09-19 17:39:02.977472Z] Running download-and-extract step. 10:39:02 INFO - Running pre-action listener: _resource_record_pre_action 10:39:02 INFO - Running main action method: download_and_extract 10:39:02 INFO - mkdir: /builds/slave/test/build/tests 10:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:02 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:39:02 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/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 10:39:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/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 10:39:03 INFO - Downloaded 1361 bytes. 10:39:03 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 10:39:03 INFO - Using the following test package requirements: 10:39:03 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:39:03 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:39:03 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:39:03 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:03 INFO - u'jsshell-linux-x86_64.zip'], 10:39:03 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:39:03 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 10:39:03 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:39:03 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:39:03 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:39:03 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 10:39:03 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:39:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:39:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:39:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:39:03 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:39:03 INFO - Expected file size: 21936151 10:39:07 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 10:39:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 10:39:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:39:07 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:39:07 INFO - Expected file size: 21936151 10:39:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 10:39:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:39:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 10:39:12 INFO - Expected file size: 14414059 10:39:12 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')]} 10:39:12 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:39:12 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/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 10:39:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/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 10:39:13 INFO - Downloaded 59559423 bytes. 10:39:13 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:39:13 INFO - mkdir: /builds/slave/test/properties 10:39:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:39:13 INFO - Writing to file /builds/slave/test/properties/build_url 10:39:13 INFO - Contents: 10:39:13 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 10:39:13 INFO - Running post-action listener: _resource_record_post_action 10:39:13 INFO - Running post-action listener: set_extra_try_arguments 10:39:13 INFO - [mozharness: 2016-09-19 17:39:13.492756Z] Finished download-and-extract step (success) 10:39:13 INFO - [mozharness: 2016-09-19 17:39:13.493027Z] Running populate-webroot step. 10:39:13 INFO - Running pre-action listener: _resource_record_pre_action 10:39:13 INFO - Running main action method: populate_webroot 10:39:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:39:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:39:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:39:13 INFO - '--tppagecycles', 10:39:13 INFO - '7'], 10:39:13 INFO - 'tests': ['tsvgm']}}, 10:39:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:39:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:39:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:39:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:39:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:13 INFO - 'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:39:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:39:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:13 INFO - 'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['damp', 'tps']}, 10:39:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:13 INFO - 'tests': ['damp', 'tps']}, 10:39:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['dromaeo_dom']}, 10:39:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:39:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['basic_compositor_video']}, 10:39:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:39:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['a11yr', 10:39:13 INFO - 'ts_paint', 10:39:13 INFO - 'tpaint', 10:39:13 INFO - 'sessionrestore', 10:39:13 INFO - 'sessionrestore_no_auto_restore', 10:39:13 INFO - 'tabpaint']}, 10:39:13 INFO - 'other-e10s': {'tests': ['a11yr', 10:39:13 INFO - 'ts_paint', 10:39:13 INFO - 'tpaint', 10:39:13 INFO - 'sessionrestore', 10:39:13 INFO - 'sessionrestore_no_auto_restore', 10:39:13 INFO - 'tabpaint']}, 10:39:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:39:13 INFO - 'ts_paint', 10:39:13 INFO - 'tpaint', 10:39:13 INFO - 'sessionrestore', 10:39:13 INFO - 'sessionrestore_no_auto_restore', 10:39:13 INFO - 'tabpaint']}, 10:39:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:39:13 INFO - 'ts_paint', 10:39:13 INFO - 'tpaint', 10:39:13 INFO - 'sessionrestore', 10:39:13 INFO - 'sessionrestore_no_auto_restore', 10:39:13 INFO - 'tabpaint']}, 10:39:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['a11yr', 10:39:13 INFO - 'ts_paint', 10:39:13 INFO - 'tpaint', 10:39:13 INFO - 'sessionrestore', 10:39:13 INFO - 'sessionrestore_no_auto_restore', 10:39:13 INFO - 'tabpaint']}, 10:39:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['a11yr', 10:39:13 INFO - 'ts_paint', 10:39:13 INFO - 'tpaint', 10:39:13 INFO - 'sessionrestore', 10:39:13 INFO - 'sessionrestore_no_auto_restore', 10:39:13 INFO - 'tabpaint']}, 10:39:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['tsvgx', 10:39:13 INFO - 'tsvgr_opacity', 10:39:13 INFO - 'tart', 10:39:13 INFO - 'tscrollx', 10:39:13 INFO - 'cart']}, 10:39:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:39:13 INFO - 'tsvgr_opacity', 10:39:13 INFO - 'tart', 10:39:13 INFO - 'tscrollx', 10:39:13 INFO - 'cart']}, 10:39:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:13 INFO - 'talos_options': ['--disable-e10s'], 10:39:13 INFO - 'tests': ['tp5o']}, 10:39:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:13 INFO - 'tests': ['tp5o']}, 10:39:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:13 INFO - 'talos_options': ['--disable-e10s', 10:39:13 INFO - '--xperf_path', 10:39:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:39:13 INFO - 'tests': ['tp5n']}, 10:39:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:39:13 INFO - 'talos_options': ['--xperf_path', 10:39:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:39:13 INFO - 'tests': ['tp5n']}}, 10:39:13 INFO - 'talos.zip': {'path': '', 10:39:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:39:13 INFO - Running post-action listener: _resource_record_post_action 10:39:13 INFO - [mozharness: 2016-09-19 17:39:13.511356Z] Finished populate-webroot step (success) 10:39:13 INFO - [mozharness: 2016-09-19 17:39:13.511445Z] Running create-virtualenv step. 10:39:13 INFO - Running pre-action listener: _resource_record_pre_action 10:39:13 INFO - Running main action method: create_virtualenv 10:39:13 INFO - Creating virtualenv /builds/slave/test/build/venv 10:39:13 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 10:39:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:39:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:39:13 INFO - Using real prefix '/usr' 10:39:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:39:14 INFO - Installing distribute.............................................................................................................................................................................................done. 10:39:16 INFO - Installing pip.................done. 10:39:16 INFO - Return code: 0 10:39:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:39:16 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')]} 10:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:16 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')]} 10:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:16 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:16 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 10:39:16 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 10:39:16 INFO - Using env: {'DISPLAY': ':0', 10:39:16 INFO - 'HOME': '/home/cltbld', 10:39:16 INFO - 'LANG': 'en_US.UTF-8', 10:39:16 INFO - 'LANGUAGE': 'en_US:en', 10:39:16 INFO - 'LOGNAME': 'cltbld', 10:39:16 INFO - 'MAIL': '/var/mail/cltbld', 10:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:16 INFO - 'MOZ_NO_REMOTE': '1', 10:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:16 INFO - 'NO_EM_RESTART': '1', 10:39:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:16 INFO - 'PWD': '/builds/slave/test', 10:39:16 INFO - 'SHELL': '/bin/bash', 10:39:16 INFO - 'SHLVL': '1', 10:39:16 INFO - 'TERM': 'linux', 10:39:16 INFO - 'TMOUT': '86400', 10:39:16 INFO - 'USER': 'cltbld', 10:39:16 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:16 INFO - '_': '/tools/buildbot/bin/python'} 10:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:16 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 10:39:16 INFO - Cleaning up... 10:39:16 INFO - Return code: 0 10:39:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:39:16 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')]} 10:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:16 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')]} 10:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:16 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:16 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 10:39:16 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 10:39:16 INFO - Using env: {'DISPLAY': ':0', 10:39:16 INFO - 'HOME': '/home/cltbld', 10:39:16 INFO - 'LANG': 'en_US.UTF-8', 10:39:16 INFO - 'LANGUAGE': 'en_US:en', 10:39:16 INFO - 'LOGNAME': 'cltbld', 10:39:16 INFO - 'MAIL': '/var/mail/cltbld', 10:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:16 INFO - 'MOZ_NO_REMOTE': '1', 10:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:16 INFO - 'NO_EM_RESTART': '1', 10:39:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:16 INFO - 'PWD': '/builds/slave/test', 10:39:16 INFO - 'SHELL': '/bin/bash', 10:39:16 INFO - 'SHLVL': '1', 10:39:16 INFO - 'TERM': 'linux', 10:39:16 INFO - 'TMOUT': '86400', 10:39:16 INFO - 'USER': 'cltbld', 10:39:16 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:16 INFO - '_': '/tools/buildbot/bin/python'} 10:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:16 INFO - Downloading/unpacking psutil>=3.1.1 10:39:16 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 10:39:16 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 10:39:16 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 10:39:16 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 10:39:16 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 10:39:16 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 10:39:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:39:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:39:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:39:18 INFO - Installing collected packages: psutil 10:39:18 INFO - Running setup.py install for psutil 10:39:18 INFO - building 'psutil._psutil_linux' extension 10:39:18 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 10:39:18 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 10:39:18 INFO - building 'psutil._psutil_posix' extension 10:39:18 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 10:39:18 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 10:39:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:39:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:39:19 INFO - Successfully installed psutil 10:39:19 INFO - Cleaning up... 10:39:19 INFO - Return code: 0 10:39:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:19 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:19 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 10:39:19 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 10:39:19 INFO - Using env: {'DISPLAY': ':0', 10:39:19 INFO - 'HOME': '/home/cltbld', 10:39:19 INFO - 'LANG': 'en_US.UTF-8', 10:39:19 INFO - 'LANGUAGE': 'en_US:en', 10:39:19 INFO - 'LOGNAME': 'cltbld', 10:39:19 INFO - 'MAIL': '/var/mail/cltbld', 10:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:19 INFO - 'MOZ_NO_REMOTE': '1', 10:39:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:19 INFO - 'NO_EM_RESTART': '1', 10:39:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:19 INFO - 'PWD': '/builds/slave/test', 10:39:19 INFO - 'SHELL': '/bin/bash', 10:39:19 INFO - 'SHLVL': '1', 10:39:19 INFO - 'TERM': 'linux', 10:39:19 INFO - 'TMOUT': '86400', 10:39:19 INFO - 'USER': 'cltbld', 10:39:19 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:19 INFO - '_': '/tools/buildbot/bin/python'} 10:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:39:19 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 10:39:19 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 10:39:19 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 10:39:19 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 10:39:19 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 10:39:19 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 10:39:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:39:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:39:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:39:21 INFO - Installing collected packages: mozsystemmonitor 10:39:21 INFO - Running setup.py install for mozsystemmonitor 10:39:21 INFO - Successfully installed mozsystemmonitor 10:39:21 INFO - Cleaning up... 10:39:21 INFO - Return code: 0 10:39:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:21 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:21 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 10:39:21 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 10:39:21 INFO - Using env: {'DISPLAY': ':0', 10:39:21 INFO - 'HOME': '/home/cltbld', 10:39:21 INFO - 'LANG': 'en_US.UTF-8', 10:39:21 INFO - 'LANGUAGE': 'en_US:en', 10:39:21 INFO - 'LOGNAME': 'cltbld', 10:39:21 INFO - 'MAIL': '/var/mail/cltbld', 10:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:21 INFO - 'MOZ_NO_REMOTE': '1', 10:39:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:21 INFO - 'NO_EM_RESTART': '1', 10:39:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:21 INFO - 'PWD': '/builds/slave/test', 10:39:21 INFO - 'SHELL': '/bin/bash', 10:39:21 INFO - 'SHLVL': '1', 10:39:21 INFO - 'TERM': 'linux', 10:39:21 INFO - 'TMOUT': '86400', 10:39:21 INFO - 'USER': 'cltbld', 10:39:21 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:21 INFO - '_': '/tools/buildbot/bin/python'} 10:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:21 INFO - Downloading/unpacking jsonschema==2.5.1 10:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:39:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:39:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:39:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:23 INFO - Downloading functools32-3.2.3-2.tar.gz 10:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:39:23 INFO - warning: no files found matching '*.txt' 10:39:23 INFO - no previously-included directories found matching 'build' 10:39:23 INFO - no previously-included directories found matching 'dist' 10:39:23 INFO - no previously-included directories found matching '.git*' 10:39:23 INFO - Installing collected packages: jsonschema, functools32 10:39:23 INFO - Running setup.py install for functools32 10:39:23 INFO - warning: no files found matching '*.txt' 10:39:23 INFO - no previously-included directories found matching 'build' 10:39:23 INFO - no previously-included directories found matching 'dist' 10:39:23 INFO - no previously-included directories found matching '.git*' 10:39:23 INFO - Successfully installed jsonschema functools32 10:39:23 INFO - Cleaning up... 10:39:23 INFO - Return code: 0 10:39:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:39:23 INFO - Using env: {'DISPLAY': ':0', 10:39:23 INFO - 'HOME': '/home/cltbld', 10:39:23 INFO - 'LANG': 'en_US.UTF-8', 10:39:23 INFO - 'LANGUAGE': 'en_US:en', 10:39:23 INFO - 'LOGNAME': 'cltbld', 10:39:23 INFO - 'MAIL': '/var/mail/cltbld', 10:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:23 INFO - 'MOZ_NO_REMOTE': '1', 10:39:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:23 INFO - 'NO_EM_RESTART': '1', 10:39:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:23 INFO - 'PWD': '/builds/slave/test', 10:39:23 INFO - 'SHELL': '/bin/bash', 10:39:23 INFO - 'SHLVL': '1', 10:39:23 INFO - 'TERM': 'linux', 10:39:23 INFO - 'TMOUT': '86400', 10:39:23 INFO - 'USER': 'cltbld', 10:39:23 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:23 INFO - '_': '/tools/buildbot/bin/python'} 10:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:39:24 INFO - Cleaning up... 10:39:24 INFO - Return code: 0 10:39:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:24 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:24 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 10:39:24 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 10:39:24 INFO - Using env: {'DISPLAY': ':0', 10:39:24 INFO - 'HOME': '/home/cltbld', 10:39:24 INFO - 'LANG': 'en_US.UTF-8', 10:39:24 INFO - 'LANGUAGE': 'en_US:en', 10:39:24 INFO - 'LOGNAME': 'cltbld', 10:39:24 INFO - 'MAIL': '/var/mail/cltbld', 10:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:24 INFO - 'MOZ_NO_REMOTE': '1', 10:39:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:24 INFO - 'NO_EM_RESTART': '1', 10:39:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:24 INFO - 'PWD': '/builds/slave/test', 10:39:24 INFO - 'SHELL': '/bin/bash', 10:39:24 INFO - 'SHLVL': '1', 10:39:24 INFO - 'TERM': 'linux', 10:39:24 INFO - 'TMOUT': '86400', 10:39:24 INFO - 'USER': 'cltbld', 10:39:24 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:24 INFO - '_': '/tools/buildbot/bin/python'} 10:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:24 INFO - Downloading/unpacking blobuploader==1.2.4 10:39:24 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 10:39:24 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 10:39:24 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 10:39:24 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 10:39:24 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 10:39:24 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 10:39:26 INFO - Downloading blobuploader-1.2.4.tar.gz 10:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:39:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:39:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:39:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:39:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:39:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:39:26 INFO - Downloading docopt-0.6.1.tar.gz 10:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:39:27 INFO - Installing collected packages: blobuploader, requests, docopt 10:39:27 INFO - Running setup.py install for blobuploader 10:39:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:39:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:39:27 INFO - Running setup.py install for requests 10:39:27 INFO - Running setup.py install for docopt 10:39:27 INFO - Successfully installed blobuploader requests docopt 10:39:27 INFO - Cleaning up... 10:39:27 INFO - Return code: 0 10:39:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39:27 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')]} 10:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:27 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')]} 10:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:27 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:27 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 10:39:27 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 10:39:27 INFO - Using env: {'DISPLAY': ':0', 10:39:27 INFO - 'HOME': '/home/cltbld', 10:39:27 INFO - 'LANG': 'en_US.UTF-8', 10:39:27 INFO - 'LANGUAGE': 'en_US:en', 10:39:27 INFO - 'LOGNAME': 'cltbld', 10:39:27 INFO - 'MAIL': '/var/mail/cltbld', 10:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:27 INFO - 'MOZ_NO_REMOTE': '1', 10:39:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:27 INFO - 'NO_EM_RESTART': '1', 10:39:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:27 INFO - 'PWD': '/builds/slave/test', 10:39:27 INFO - 'SHELL': '/bin/bash', 10:39:27 INFO - 'SHLVL': '1', 10:39:27 INFO - 'TERM': 'linux', 10:39:27 INFO - 'TMOUT': '86400', 10:39:27 INFO - 'USER': 'cltbld', 10:39:27 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:27 INFO - '_': '/tools/buildbot/bin/python'} 10:39:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:39:27 INFO - Running setup.py (path:/tmp/pip-OnxymP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:39:27 INFO - Running setup.py (path:/tmp/pip-gFI6iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:39:27 INFO - Running setup.py (path:/tmp/pip-VfkYyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:39:28 INFO - Running setup.py (path:/tmp/pip-w6DvyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:39:28 INFO - Running setup.py (path:/tmp/pip-2ypnc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:39:28 INFO - Running setup.py (path:/tmp/pip-ceWRse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:39:28 INFO - Running setup.py (path:/tmp/pip-c23j62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:39:28 INFO - Running setup.py (path:/tmp/pip-32FOpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:39:28 INFO - Running setup.py (path:/tmp/pip-6_JHJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:39:28 INFO - Running setup.py (path:/tmp/pip-jgsUtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:39:28 INFO - Running setup.py (path:/tmp/pip-34EDeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:39:28 INFO - Running setup.py (path:/tmp/pip-LYmgAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:39:28 INFO - Running setup.py (path:/tmp/pip-Cd8myv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:39:28 INFO - Running setup.py (path:/tmp/pip-1TBpA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:28 INFO - Running setup.py (path:/tmp/pip-NZ8fzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:39:28 INFO - Running setup.py (path:/tmp/pip-IGDpWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:39:28 INFO - Running setup.py (path:/tmp/pip-oiAAeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:39:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:39:29 INFO - Running setup.py install for manifestparser 10:39:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Running setup.py install for mozcrash 10:39:29 INFO - Running setup.py install for mozdebug 10:39:29 INFO - Running setup.py install for mozdevice 10:39:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Running setup.py install for mozfile 10:39:29 INFO - Running setup.py install for mozhttpd 10:39:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Running setup.py install for mozinfo 10:39:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Running setup.py install for mozInstall 10:39:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:39:29 INFO - Running setup.py install for mozleak 10:39:29 INFO - Running setup.py install for mozlog 10:39:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:39:30 INFO - Running setup.py install for moznetwork 10:39:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:39:30 INFO - Running setup.py install for mozprocess 10:39:30 INFO - Running setup.py install for mozprofile 10:39:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:39:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:39:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:39:30 INFO - Running setup.py install for mozrunner 10:39:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:39:30 INFO - Running setup.py install for mozscreenshot 10:39:30 INFO - Running setup.py install for moztest 10:39:30 INFO - Running setup.py install for mozversion 10:39:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:39:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:39:30 INFO - Cleaning up... 10:39:30 INFO - Return code: 0 10:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39: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')]} 10:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39: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')]} 10:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:30 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:30 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 10:39:30 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 10:39:30 INFO - Using env: {'DISPLAY': ':0', 10:39:30 INFO - 'HOME': '/home/cltbld', 10:39:30 INFO - 'LANG': 'en_US.UTF-8', 10:39:30 INFO - 'LANGUAGE': 'en_US:en', 10:39:30 INFO - 'LOGNAME': 'cltbld', 10:39:30 INFO - 'MAIL': '/var/mail/cltbld', 10:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:30 INFO - 'MOZ_NO_REMOTE': '1', 10:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:30 INFO - 'NO_EM_RESTART': '1', 10:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:30 INFO - 'PWD': '/builds/slave/test', 10:39:30 INFO - 'SHELL': '/bin/bash', 10:39:30 INFO - 'SHLVL': '1', 10:39:30 INFO - 'TERM': 'linux', 10:39:30 INFO - 'TMOUT': '86400', 10:39:30 INFO - 'USER': 'cltbld', 10:39:30 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:30 INFO - '_': '/tools/buildbot/bin/python'} 10:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:39:31 INFO - Running setup.py (path:/tmp/pip-LcO9iU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:39:31 INFO - Running setup.py (path:/tmp/pip-TpJTYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:39:31 INFO - Running setup.py (path:/tmp/pip-LGjHd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:39:31 INFO - Running setup.py (path:/tmp/pip-agkSTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:39:31 INFO - Running setup.py (path:/tmp/pip-LyIs3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:39:31 INFO - Running setup.py (path:/tmp/pip-fu3030-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:39:31 INFO - Running setup.py (path:/tmp/pip-JHJT7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:39:31 INFO - Running setup.py (path:/tmp/pip-IDs8pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:39:31 INFO - Running setup.py (path:/tmp/pip-qoV3P3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:39:31 INFO - Running setup.py (path:/tmp/pip-cTKmeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:39:31 INFO - Running setup.py (path:/tmp/pip-8rfd2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:39:31 INFO - Running setup.py (path:/tmp/pip-RpPe4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:39:31 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)) 10:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:39:31 INFO - Running setup.py (path:/tmp/pip-OX_lVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:39:32 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)) 10:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:39:32 INFO - Running setup.py (path:/tmp/pip-ujbKkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:39:32 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)) 10:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:32 INFO - Running setup.py (path:/tmp/pip-QjqBnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:39:32 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)) 10:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:39:32 INFO - Running setup.py (path:/tmp/pip-cxHZeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:39:32 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)) 10:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:39:32 INFO - Running setup.py (path:/tmp/pip-LwJNJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:39:32 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)) 10:39:32 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)) 10:39:32 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)) 10:39:32 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)) 10:39:32 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)) 10:39:32 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)) 10:39:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:39:32 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 10:39:32 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 10:39:32 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 10:39:32 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 10:39:32 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 10:39:32 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 10:39:34 INFO - Downloading blessings-1.6.tar.gz 10:39:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:39:34 INFO - Installing collected packages: blessings 10:39:34 INFO - Running setup.py install for blessings 10:39:34 INFO - Successfully installed blessings 10:39:34 INFO - Cleaning up... 10:39:34 INFO - Return code: 0 10:39:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:39:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:39:34 INFO - Reading from file tmpfile_stdout 10:39:34 INFO - Current package versions: 10:39:34 INFO - argparse == 1.2.1 10:39:34 INFO - blessings == 1.6 10:39:34 INFO - blobuploader == 1.2.4 10:39:34 INFO - docopt == 0.6.1 10:39:34 INFO - functools32 == 3.2.3-2 10:39:34 INFO - jsonschema == 2.5.1 10:39:34 INFO - manifestparser == 1.1 10:39:34 INFO - mozInstall == 1.12 10:39:34 INFO - mozcrash == 0.17 10:39:34 INFO - mozdebug == 0.1 10:39:34 INFO - mozdevice == 0.48 10:39:34 INFO - mozfile == 1.2 10:39:34 INFO - mozhttpd == 0.7 10:39:34 INFO - mozinfo == 0.9 10:39:34 INFO - mozleak == 0.1 10:39:34 INFO - mozlog == 3.4 10:39:34 INFO - moznetwork == 0.27 10:39:34 INFO - mozprocess == 0.23 10:39:34 INFO - mozprofile == 0.28 10:39:34 INFO - mozrunner == 6.12 10:39:34 INFO - mozscreenshot == 0.1 10:39:34 INFO - mozsystemmonitor == 0.3 10:39:34 INFO - moztest == 0.7 10:39:34 INFO - mozversion == 1.4 10:39:34 INFO - psutil == 3.1.1 10:39:34 INFO - requests == 1.2.3 10:39:34 INFO - wsgiref == 0.1.2 10:39:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:39:34 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')]} 10:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:34 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')]} 10:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:34 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:34 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 10:39:34 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 10:39:34 INFO - Using env: {'DISPLAY': ':0', 10:39:34 INFO - 'HOME': '/home/cltbld', 10:39:34 INFO - 'LANG': 'en_US.UTF-8', 10:39:34 INFO - 'LANGUAGE': 'en_US:en', 10:39:34 INFO - 'LOGNAME': 'cltbld', 10:39:34 INFO - 'MAIL': '/var/mail/cltbld', 10:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:34 INFO - 'MOZ_NO_REMOTE': '1', 10:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:34 INFO - 'NO_EM_RESTART': '1', 10:39:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:34 INFO - 'PWD': '/builds/slave/test', 10:39:34 INFO - 'SHELL': '/bin/bash', 10:39:34 INFO - 'SHLVL': '1', 10:39:34 INFO - 'TERM': 'linux', 10:39:34 INFO - 'TMOUT': '86400', 10:39:34 INFO - 'USER': 'cltbld', 10:39:34 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:34 INFO - '_': '/tools/buildbot/bin/python'} 10:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 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)) 10:39:34 INFO - Cleaning up... 10:39:34 INFO - Return code: 0 10:39:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:39:34 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')]} 10:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:34 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')]} 10:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:39:34 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 0x7fcf1b588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d4d790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, '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': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', '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 10:39:34 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 10:39:34 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 10:39:34 INFO - Using env: {'DISPLAY': ':0', 10:39:34 INFO - 'HOME': '/home/cltbld', 10:39:34 INFO - 'LANG': 'en_US.UTF-8', 10:39:34 INFO - 'LANGUAGE': 'en_US:en', 10:39:34 INFO - 'LOGNAME': 'cltbld', 10:39:34 INFO - 'MAIL': '/var/mail/cltbld', 10:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:34 INFO - 'MOZ_NO_REMOTE': '1', 10:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:34 INFO - 'NO_EM_RESTART': '1', 10:39:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:34 INFO - 'PWD': '/builds/slave/test', 10:39:34 INFO - 'SHELL': '/bin/bash', 10:39:34 INFO - 'SHLVL': '1', 10:39:34 INFO - 'TERM': 'linux', 10:39:34 INFO - 'TMOUT': '86400', 10:39:34 INFO - 'USER': 'cltbld', 10:39:34 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:34 INFO - '_': '/tools/buildbot/bin/python'} 10:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:39:35 INFO - Cleaning up... 10:39:35 INFO - Return code: 0 10:39:35 INFO - Running post-action listener: _resource_record_post_action 10:39:35 INFO - Running post-action listener: _start_resource_monitoring 10:39:35 INFO - Starting resource monitoring. 10:39:35 INFO - [mozharness: 2016-09-19 17:39:35.153677Z] Finished create-virtualenv step (success) 10:39:35 INFO - [mozharness: 2016-09-19 17:39:35.154130Z] Running install step. 10:39:35 INFO - Running pre-action listener: _resource_record_pre_action 10:39:35 INFO - Running main action method: install 10:39:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:39:35 INFO - Reading from file tmpfile_stdout 10:39:35 INFO - Detecting whether we're running mozinstall >=1.0... 10:39:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:39:35 INFO - Reading from file tmpfile_stdout 10:39:35 INFO - Output received: 10:39:35 INFO - Usage: mozinstall [options] installer 10:39:35 INFO - Options: 10:39:35 INFO - -h, --help show this help message and exit 10:39:35 INFO - -d DEST, --destination=DEST 10:39:35 INFO - Directory to install application into. [default: 10:39:35 INFO - "/builds/slave/test"] 10:39:35 INFO - --app=APP Application being installed. [default: firefox] 10:39:35 INFO - mkdir: /builds/slave/test/build/application 10:39:35 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'] 10:39:35 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 10:39:52 INFO - Reading from file tmpfile_stdout 10:39:52 INFO - Output received: 10:39:52 INFO - /builds/slave/test/build/application/firefox/firefox 10:39:52 INFO - Running post-action listener: _resource_record_post_action 10:39:52 INFO - [mozharness: 2016-09-19 17:39:52.387928Z] Finished install step (success) 10:39:52 INFO - [mozharness: 2016-09-19 17:39:52.388177Z] Running run-tests step. 10:39:52 INFO - Running pre-action listener: _resource_record_pre_action 10:39:52 INFO - Running main action method: run_tests 10:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:39:52 INFO - Python 2.7.3 10:39:52 INFO - Return code: 0 10:39:52 INFO - grabbing minidump binary from tooltool 10:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:39:52 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 0x1d4a930>, '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 0x1d4b830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d4bcc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:39:52 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 10:39:52 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 10:39:52 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 10:39:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:39:52 INFO - Return code: 0 10:39:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:39:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:39:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:39:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:39:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 10:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 10:39:52 INFO - Using env: {'DISPLAY': ':0', 10:39:52 INFO - 'HOME': '/home/cltbld', 10:39:52 INFO - 'LANG': 'en_US.UTF-8', 10:39:52 INFO - 'LANGUAGE': 'en_US:en', 10:39:52 INFO - 'LOGNAME': 'cltbld', 10:39:52 INFO - 'MAIL': '/var/mail/cltbld', 10:39:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:39:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:39:52 INFO - 'MOZ_NO_REMOTE': '1', 10:39:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:39:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:39:52 INFO - 'NO_EM_RESTART': '1', 10:39:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:39:52 INFO - 'PWD': '/builds/slave/test', 10:39:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:39:52 INFO - 'SHELL': '/bin/bash', 10:39:52 INFO - 'SHLVL': '1', 10:39:52 INFO - 'TERM': 'linux', 10:39:52 INFO - 'TMOUT': '86400', 10:39:52 INFO - 'USER': 'cltbld', 10:39:52 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173', 10:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:39:52 INFO - '_': '/tools/buildbot/bin/python'} 10:39:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 10:39:52 INFO - mozversion application_buildid: 20160919063000 10:39:52 INFO - mozversion application_changeset: e0b4bad921cd807f96b911ffb6339bf67c913194 10:39:52 INFO - mozversion application_display_name: Nightly 10:39:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:39:52 INFO - mozversion application_name: Firefox 10:39:52 INFO - mozversion application_remotingname: firefox 10:39:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:39:52 INFO - mozversion application_vendor: Mozilla 10:39:52 INFO - mozversion application_version: 51.0a1 10:39:52 INFO - mozversion platform_buildid: 20160919063000 10:39:52 INFO - mozversion platform_changeset: e0b4bad921cd807f96b911ffb6339bf67c913194 10:39:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:39:52 INFO - mozversion platform_version: 51.0a1 10:39:52 INFO - using testdate: 1474306792 10:39:52 INFO - actual date: 1474306792 10:39:52 INFO - starting webserver on 'localhost:53571' 10:39:52 INFO - SUITE-START | Running 2 tests 10:39:52 INFO - TEST-START | dromaeo_css 10:39:52 INFO - operating with platform_type : linux_ 10:39:52 INFO - Initialising browser for dromaeo_css test... 10:39:52 INFO - TEST-INFO | started process 9729 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmzryvD/profile http://localhost:53571/getInfo.html) 10:39:56 INFO - PROCESS | 9729 | __metrics Screen width/height:1600/1200 10:39:56 INFO - PROCESS | 9729 | colorDepth:24 10:39:56 INFO - PROCESS | 9729 | Browser inner width/height: 1024/697 10:39:56 INFO - PROCESS | 9729 | __metrics 10:39:57 INFO - PROCESS | 9729 | 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" 10:39:57 INFO - PROCESS | 9729 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:39:57 INFO - PROCESS | 9729 | console.error: PushService: 10:39:57 INFO - PROCESS | 9729 | stateChangeProcessEnqueue: Error transitioning state 10:39:57 INFO - PROCESS | 9729 | UnknownError 10:39:58 INFO - TEST-INFO | 9729: exit 0 10:39:58 INFO - Browser initialized. 10:39:58 INFO - Running cycle 1/1 for dromaeo_css test... 10:39:58 INFO - TEST-INFO | started process 9811 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmzryvD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:40:10 INFO - PROCESS | 9811 | RSS: Main: 172687360 10:40:10 INFO - PROCESS | 9811 | 10:40:23 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.05006 seconds. 10:40:40 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 10:40:50 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 10:41:01 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 10:41:01 INFO - PROCESS | 9811 | 1474306861990 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] 10:41:06 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.03329 seconds. 10:41:25 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 10:41:38 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:41:40 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.16671 seconds. 10:41:44 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 10:42:07 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 10:42:27 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 10:42:32 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.05001 seconds. 10:42:50 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.08337 seconds. 10:42:57 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:42:59 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:43:10 INFO - PROCESS | 9811 | Cycle 1(1): loaded http://localhost:53571/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53571/tests/dromaeo/cssquery-ext.html) 10:43:12 INFO - PROCESS | 9811 | RSS: Main: 360796160 10:43:12 INFO - PROCESS | 9811 | 10:43:36 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:43:51 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:43:59 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:44:30 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00011 seconds. 10:44:36 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:45:02 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:45:04 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 10:45:14 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:45:16 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:45:18 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:45:25 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:45:27 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 10:45:30 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:45:32 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:45:41 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 10:45:44 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:45:46 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:45:57 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:46:05 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:46:15 INFO - PROCESS | 9811 | Cycle 1(1): loaded http://localhost:53571/tests/dromaeo/cssquery-ext.html (next: http://localhost:53571/tests/dromaeo/cssquery-jquery.html) 10:46:15 INFO - PROCESS | 9811 | RSS: Main: 277798912 10:46:15 INFO - PROCESS | 9811 | 10:46:19 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:46:22 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 10:46:26 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:46:28 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:46:34 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:46:52 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:47:02 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:47:12 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:47:20 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:47:29 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:47:39 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:47:45 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:47:50 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01674 seconds. 10:47:59 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:48:04 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:48:15 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:48:23 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:48:25 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:48:35 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:48:45 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:48:55 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:48:57 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:49:07 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:49:17 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:49:17 INFO - PROCESS | 9811 | Cycle 1(1): loaded http://localhost:53571/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53571/tests/dromaeo/cssquery-mootools.html) 10:49:18 INFO - PROCESS | 9811 | RSS: Main: 218521600 10:49:18 INFO - PROCESS | 9811 | 10:49:23 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0334 seconds. 10:49:29 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:49:31 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:49:37 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:50:03 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:50:17 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:50:31 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:50:37 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:50:41 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:50:47 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:51:01 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:51:21 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:51:34 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:52:01 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:52:20 INFO - PROCESS | 9811 | Cycle 1(1): loaded http://localhost:53571/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53571/tests/dromaeo/cssquery-prototype.html) 10:52:21 INFO - PROCESS | 9811 | RSS: Main: 219635712 10:52:21 INFO - PROCESS | 9811 | 10:52:24 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.03342 seconds. 10:52:30 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 10:52:36 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:52:39 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:52:50 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:53:02 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:53:08 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:53:10 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:53:14 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:53:20 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:53:24 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:53:36 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:53:43 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:53:44 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 10:53:51 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:53:54 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:53:59 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:54:08 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 10:54:14 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:54:18 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 10:54:26 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:54:31 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01673 seconds. 10:54:47 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:54:49 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 10:54:55 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:55:15 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:55:23 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01675 seconds. 10:55:23 INFO - PROCESS | 9811 | Cycle 1(1): loaded http://localhost:53571/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53571/tests/dromaeo/cssquery-yui.html) 10:55:24 INFO - PROCESS | 9811 | RSS: Main: 214786048 10:55:24 INFO - PROCESS | 9811 | 10:55:29 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 10:55:30 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 10:55:53 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:56:09 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:56:17 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:56:56 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:57:20 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 10:57:30 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:57:38 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:57:46 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:57:48 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:57:58 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 10:58:02 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 10:58:24 INFO - PROCESS | 9811 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 10:58:27 INFO - PROCESS | 9811 | Cycle 1(1): loaded http://localhost:53571/tests/dromaeo/cssquery-yui.html (next: http://localhost:53571/tests/dromaeo/cssquery-dojo.html) 10:58:27 INFO - PROCESS | 9811 | RSS: Main: 303890432 10:58:27 INFO - PROCESS | 9811 | 10:58:27 INFO - PROCESS | 9811 | __start_tp_report 10:58:27 INFO - PROCESS | 9811 | _x_x_mozilla_page_load 10:58:27 INFO - PROCESS | 9811 | _x_x_mozilla_page_load_details 10:58:27 INFO - PROCESS | 9811 | |i|pagename|runs| 10:58:27 INFO - PROCESS | 9811 | |0;dojo.html;2899.826010439374;2984.3583629519662;2991.506401443762;3003.9399212015746;3033.1658793831693;4987.800487980477;5086.593072554196;5157.845264642062;5222.060029194743;5237.031149237394;5123;5141.717205553693;5250.133727934886;5265.578753699704;5347.786088556462;4669.042846216517;4710.387649605546;4723.10261050401;4804.351412559304;4829.90340193196;4691.437027556698;4749.62003039756;4750.881227969311;4755.381800365947;4812.927806082894;4713.481517033124;4749.026449577828;4752.881177970544;4757.809687612508;4819.566239038486;4624.352590637313;4639.86080417588;4651.604613607848;4693.929591056137;4775.976120119377;4660.883477913046;4661.953380466186;4671.85984420468;4732.242841145435;4754.429468463772;5062.493750624908;5111.156659151273;5114.641975061747;5117.744112794346;5156.561692256163;5384.030874442598;5437.891242175174;5455.990641731253;5467.4403610573745;5490.945090549083;5549.5005449509335;5575.944240557583;5590.748416321276;5650.51970582501;5654.773809047633;5199.0381779370555;5211.635185536976;5211.765470553834;5214.45248248936;5263.105272103751;5506.174073888868;5571.9442805572235;5709.486146246858;5716.657000579979;5718.142278658234;5287.920681189786;5339.279197308314;5376.1398176291605;5422.972885135549;5433.6196466247775;5371.973140134274;5412.296401467784;5467.9453205467435;5480.479354461319;5485.588580856452;5393;5398.73006349681;5400.297961265089;5401.243825864382;5402.567794576425;5291.04761142998;5394.676319420769;5405.270288511001;5426.186072089139;5465.836024919259;4292.098659281523;4297.290946993773;4345.9348109778375;4347.413099231563;4394.472663280363;3851.2105018471148;3858.9421158682644;3868;3889.552701439314;3911.02224443889;5288.788448462018;5292.100342941709;5295.285136506599;5296.152615581488;5297.682139071668;5260.684358938476;5268.28878101459;5441.83674489766;5516.3380394352935;5535.889282214333;5111.616628752859;5116.923246151311;5121.308623335876;5132.204508301219;5179.249008893765;4775.259834725553;4777.8327758528285;4781.976090119527;4786.090642777861;4793.760311984387;5582.525485333792;5587.664740115606;5633.380328163898;5640.097584386478;5651.321841378978;5394.298741163623;5447.719785850243;5449.809256675997;5507.807226747044;5514.80698175578;5624.240727501735;5629.043062679436;5631.183478395692;5632.521235694848;5636.351819540805;5296.470352964827;5304.416514183359;5362.624616276823;5368.865973200692;5372.006178856924;5534.861628459321;5547.750351234285;5551.944480555143;5553.611247212818;5554.972225138848;5349.839504814861;5385.703786291635;5392.5416339611575;5401.864953376197;5405.78376864921;5343.7862485500145;5406.621536492407;5427.023135835588;5498.890022199614;5502.834914952397;5367.919481207707;5370.248165256945;5372.704501252469;5378.220158077135;5422.024035673617;5454.318210223722;5460.126379779216;5460.726963651881;5467.261919640798;5533.667979921218;5215.843524694265;5254.816081437131;5261.7895284189735;5299.894002119859;5348.9465105348445 10:58:27 INFO - PROCESS | 9811 | |1;ext.html;550.2226620484909;585.4087371754528;608.1546650156281;609.1563184988789;626.3423405424303;32282.192945176383;43174.56825431745;44139.337909931375;44149.558504414985;44260.55739442605;46215.76892115543;46328.305075423894;46589.30116048262;46669.766651166785;46703.29945050819;8121.918780812205;8988.235999940012;9058.320625953047;9081.818363632723;9139.862902056475;7963.840723185533;10656.413897235649;10863.51114199859;10896.564137434492;11273.210875238736;11057;11114.388708621063;11124.61063862765;11177.608783692573;11183.664490065312;10879.945600271987;11052.060574851066;11083.445827708581;11087.445627718662;11089.55641774332;10656.307340022873;10739.140868730561;10745;10750.892491075067;10760.569577216902;43354.783226083986;43566.12867742659;43909.12181756347;44087;44128.558714412764;3478.565179352581;3485.581730192393;3919.4708714323488;4046.6358027777346;4182.079942412694;31371.529427058613;31953;31971;31995;32113;4278.978605106954;4385.758783266919;4483.080968401502;4642.071585682843;4685.812567497296;321185.3940730305;325751;326860;327141;329440;52324;52845.471545284825;52893.206601901;52897.206541901905;52953.67615809636;62292;62301.68849155725;62363;62471.687641562414;62531.24812511264;16545.6690866182;16636.33454661824;16766.413175538797;16788.160591970354;16793;11652.41737913101;11876.406179690981;12490.250584964931;12823.871761282267;12945.54690585825;16631.168441577873;16901.408450704166;16966;17154.828451715573;17177.141142942804;6395.6482393468805;6463.57986730861;6517.770750830398;6652.467802575782;6732.259451460335;981.40134517944;987.2151639446657;1066.7279843639822;1123.8988491035682;1145.1869172887343;31967.680323196935;32061.839690801862;32171.51742723861;32185.195370115933;32566;1074.8172810622214;1108.4521967552348;1108.8225883858706;1122.45560902962;1167.235460773195;16756.497305080866;16777.412790552273;16914.154292285337;16919.407820726214;16970.660586788195;497449;497830;498401;499795.53169258824;500371;59659;59850.70074649599;60076.39923600796;60084.69957650184;60108;1207.4687137659403;1238.7894058009979;1303.680598253396;1350.770369037266;1391.0679844504207;28401.28996775097;32889.67110328866;33074.503882442266;33404;33662.83168584141;50885.236721449946;51810.481895180565;51944.22083668823;51985.48014519807;52003.47996519986;312476;313467;314841;314897;314971;27573.31066723348;28618.141455756362;28788.712112878602;30491;41749;48175.759121204166;48327;48361.51638483711;48439;50088;39657.12000799697;40015.599844002354;40951;42202.577974219865;42531.57468425276;39536.40695389629;40393.39409908795;40785;41192.588074118874;41781;39761.60238397695;39871.80064099661;40808.79595602003;41084.58915410927;41264;32905.50641740328;36122;36447.8034955121;36497;36950.63049369472;26998.460030799666;27118.864405677847;27165.320866978484;27482.547513707093;27612.585811212448 10:58:27 INFO - PROCESS | 9811 | |2;jquery.html;374.35423893783246;398.13803116253314;409.66202882621843;411.8085090432948;413.39644119585404;13774.449022039118;15706.136162511057;19496.707549386734;20013;20285.898570507165;19392.70910936337;19672.326127679815;19865.304714335;19938.601227975432;20000.59998800023;5682.801101961432;5711;5844.766209351621;5928.851778705523;5931.258592675916;5790.015697331463;5793.449622285876;5825.388334224909;5832.241808564881;5834.241548598677;4094.488188976378;4296.076343586128;6188.195534580498;6399.424051835334;6454.709538070798;6186.443220110212;6309;6514.21829380475;6522.445592124652;6540.476761859064;5548.58385621076;5579.023670857584;5583.078791999315;5587.916181257285;5591.552675785927;22969.88515057414;23719.05123795046;23770.168044118545;23789;23961.640575391382;2493.7256901740793;2529.582618867903;2561.6541766861424;2672.3853513691765;2992.9524666366597;14199.929000355036;14847.109173449628;14986.250687465583;15143.318550665137;15181;1906.075553356621;1914.2630087416305;1937.18084498077;1970.9211631534722;1993.8703984240979;82921.58539207265;84470;85303.57348213281;86482.5675871623;87909.56045219733;10589.652225626009;10687.465626718633;10833.620823271305;11012.779744405227;11190.104791616652;15552.45566405193;15560.922195389177;15611.141387223812;15664.843351566338;15693.607659808367;7996.280334769898;8132.634031468599;8385.454945428528;8523.06246312905;8557.187067228604;7984;7989.800254993672;8037.196280372033;8234.547099909443;8239.258466738022;6617.205935287796;6746.83132921671;6759.493038022069;6772.492063095189;6838.897416538756;1934.3906669399094;1993.5016245938516;2000.1099510717881;2022.7977202279653;2036.409441262047;909.3725329522608;991.7024892532212;992.3549692699803;1039.7660526381503;1040.9011143941314;18680;19708.40874773759;20137.09383077765;20315.8984205081;20382.7196288781;973.0755782007121;975.0834215837091;980.5097451274363;991.7570195302197;999.9100080992746;9874.753131171778;11270;11328.150388720878;11419.771604567863;11456.770864582662;446345;447538;450944;471823;473129;51774.22338664923;86618.56690716506;87732.12267877367;89296.553517232;89443.55278223698;1007.214372789211;1037.232447988483;1045.9738506537396;1078.1536493852445;1078.8435676826973;17245.91245582581;17742.29030838752;18057.367992120213;18065.909670451565;18142.32911241052;40811.79594102011;41793.58206417897;41952.790236048626;41994.37008444815;42761;55219;56734.71632641811;58245.37895794784;58800.70599646975;59408.70295648494;8019.75693767467;8060.39547033963;8164.83670326602;8219.095899450936;8500.57497125129;13084.345782711016;13106.541271055466;13513.256770877697;13806.93096534511;14255.287235638383;11018.39398833072;11073.390963497084;11536.36549989725;11918.106142039554;12000.279983200686;11100.611478598534;11658.18392712502;11669.941650291694;11765.52937882475;12351.93824030874;10985.450727463434;11348.829767553329;11408.94295528517;11553.133514986577;12095.81856272139;8020.398470114881;8199.303059240032;8273.089960104264;8287.171282871665;8384.58077096155;10931.344119352865;11300.152488563555;11558.30650160993;11622.883771162518;11866.703332416759 10:58:27 INFO - PROCESS | 9811 | |3;mootools.html;1308.4504508106606;1460.3793387810179;1485.242526311581;1617.1590772798145;1791.9910400447995;7390.778276651696;8175.3868459865525;8413.663453461853;8426.578671066438;8687.305015598753;8458.365622578316;8524.104968978245;8528.91471085289;8535.445196062266;8538.359623028267;2348.659444380564;2370.7273663528745;2371.0515793682493;2376.5128148729555;2379.8691071991034;2290.5075408787106;2345.038534200978;2367.10050180931;2373.347329484397;2375.8930848111877;2343.906243750248;2361.0319768894756;2366.6923299971068;2376.192094687806;2382.9157733231336;2340.707411573553;2355.9882200588972;2362.126013375054;2367.1241640593003;2377.690900182965;2242.998074594569;2310.87290199039;2321.8374713770045;2322.3381336319235;2327.674125622414;7404.370628496585;7598.20218877021;8419.915800841974;8448.493090414595;8476.703315383993;4528.43394575678;4650.488446270908;4696.553827386393;4799.952000479985;4849.951500484985;3055.1751027222526;3216.903492895217;3286.5891763529557;3293.950590741141;3295.0773783340464;1097.6871591596437;1104.2711810205226;1107.7729065541544;1119.7088756923176;1121.6130434999911;11683.299002059903;11734.354610496419;11743.471543780464;11750.764984700429;11808.173427860052;4188.979055104705;4314.848980285675;4484.798184081724;4490.753008584494;4653.325267836145;9887.851682224773;9888.851667224997;9901.653442129491;9902.752431189134;9932.453715045598;4991;5006.799728010911;5009.223570346602;5009.223570346602;5022.372203474566;4479.686421950498;4572.634189264851;4591.977040114778;4595.839145629887;4609.723416595015;3842.077901303667;3873.9418908716393;3939.684825213976;3947.940780888289;4035;1284.069049938787;1299.811527328532;1308.5681725030718;1319.5843309357522;1322.2661422910378;766.6894907562446;907.8865141857268;924.4499522783933;924.8658944453017;967.3277072434731;5597.6641401516035;5640.85897852557;5757.913631295534;5817.767289308465;5842.532597392198;916.225789208118;928.7910220200536;951.0774548687762;993.4138858073771;1018.3177271228295;3881.1849511602313;3937.645611894943;4022.8189731462153;4030.6775457963295;4039.333509970822;9948.950255248823;10724.892751072546;10796.082333001626;10827.891721082688;10829;10447.738806529898;10711.517981690842;10728.785424291627;10751.462426878781;10756.677299681021;7717;7810.414218933603;7869.803254918673;7885.566293853778;7888.447808653454;5626.156815847375;5646.153077038477;5661.660300381825;5768.1347797828985;5838.883222335445;9467.337286389886;9486.715398538056;9534.141927226583;9535.666251681158;9638.95180524093;9206.769830754285;9467.147956683932;9829.262805289774;9840.458774767456;9943.751406214902;7618.961905190438;7686.269804368576;7702.884456733265;7708.4218683600075;7743.380529557735;8206.548639824628;8292.958535207285;8298.460600060977;8315.417920745731;8331.666733330841;7165.713371465083;7321.053858708265;7514;7544.396448284234;7621.047369078865;7131.358177763818;7307.525010874276;7379.8155046124275;7606.619669016416;7628.237176282328;6913.89629155553;6986.266442023718;6989.475789315923;7008.194057683431;7056.929430705627;4174.580583092423;4533.682642215013;4600.355950166912;4613.861584152481;4669.4863565007145;4277.721948073366;4633.212353899778;4648.581627653527;4676.719396836201;5009.148444764326 10:58:27 INFO - PROCESS | 9811 | |4;prototype.html;309.0265663161044;317.1658538044942;325.37040826001686;329.8713501734324;338.51223871086574;5159.226116082585;5240.475952404758;5349.7592608332625;5362.973185134074;5377.946220537793;5098.898022039557;5281.815136470224;5309.327561818025;5313.910648317095;5332.733363331828;4145.979270103645;4179.185058913506;4187.937180942288;4190.916181676364;4210.536840947509;3982.8406863725418;4075.857344992926;4101.528324242706;4182.159849666153;4183.937240941388;4111.198316328318;4124.505059392877;4144.9585504145025;4146.398772178032;4158.6465150462245;4130.112025914428;4149.792510374469;4169.874903752908;4174.645155161815;4190.874273771792;3494.9825250873837;3617.5297211362617;3703.4444833274956;3704.0369503928905;3705.573859006222;5124.461931497213;5137.6660517066275;5143.408508021588;5157.8452646420665;5171.7672704728375;4667.836625718118;4694.507076756926;4696.506866779007;4712.151812673717;4774.9522504775205;5232.293640358541;5460.907818436329;5575.52248654107;5594.384617692051;5614.203454894436;3913.882583522499;3918.7648741075627;3931.410288456726;3953.8418463261437;3959.4852669152824;5828.184054232411;5845.41545845412;5846.269216347999;5850.64896106235;5864.384239654817;3980.7412518186225;4111.650509706708;4124.319487284624;4130.876073717794;4141.855035073758;4097.651699605507;4112.383142528644;4140.15126898985;4141.585841415835;4151.813168407429;4396.780160991938;4529.954700452953;4567.657425693086;4698.412698412699;4722.480527142012;5540.584456165804;5552.916706249329;5590.804321848797;5625.578081643893;5643.548516118701;1415.6531649745673;1424.5370254667275;1427.578864235053;1428.457186269232;1434.813474248341;934.780326623243;972.2610815780058;977.731123940922;984.5864736810556;989.1394486796389;5275.155975043975;5285.709285989307;5293.947060529423;5293.994141113176;5412.702301373462;5631.8592035199445;5650.513773939453;5650.886982260414;5682.920245153407;5715.0853064936355;5258.421573626898;5327.920081198785;5333.893322133536;5337.306150200449;5378.354597448331;2951.394964032368;2982.1799005273724;2999.145243605562;3001.9849900750355;3013.367192889518;5620.21799209394;5766.682832444172;5820.613548445202;5827.912581311284;5883.646981181143;5724.109324790954;5726.79956201531;5737.512311453574;5738.426157384228;5752.453516915886;5823.854403639858;5834.474897259267;5849.532037436995;5858.150568167592;5881.794137205177;5464.371597266365;5644;5741.875506062784;5741.885162296647;5753.798617048383;5636.408177141341;5681.806820567562;5683.602147849776;5727.11229759388;5727.455891690282;5733.971330143323;5840.926471102867;5927.170196172453;6017.18767966319;6025.909611355745;4065.837366505425;4073.9185216294914;4104.548499664975;4118.567550407164;4124.443200167939;5497.230387745799;5581.190727344593;5655.915161272666;5789.363170051207;5813.331466881194;5493.7802487900035;5552.7778888843995;5562.193481945176;5580;5609.579281553982;5530.198121272473;5547.861303467284;5557.305336832896;5587.636803607753;5597.524210441995;5240.606954478506;5479.424660410759;5503.752331145028;5513.09034009384;5518.91721624184;5595.860103497445;5606.215129881902;5611.74747136388;5674.233978412862;5682.801101961411;5293.947060529499;5490.984167928946;5543.861403464945;5553.333599968029;5560.916586251128 10:58:27 INFO - PROCESS | 9811 | |5;yui.html;744.7728442824937;762.3710438887919;765.4106338119648;823.946443481174;875.2079368171804;14446.133232006086;15394.769078463833;15443.382264709426;15450.845491545082;15468.613284667888;14739.263036848142;15414.922925385357;15565.221738913036;15578.688426231469;15657.921710391432;804.175719887116;822.1408627983772;823.979400514986;826.36782861111;826.4173757500978;648.4358608011014;668.6990854115643;797.2665148063795;819.2790344496834;824.6247957179469;871.4901782457271;874.2568816505951;874.4971641306242;874.7069731639908;874.8993865705462;842.9030661473947;860.2086080805674;863.0592654007131;863.2575984653193;863.2575984653193;500.93237412949514;508.47118996243864;606.9833029675276;620.0760866309212;625.4746013348763;14666.266686665624;14676;14698.853011469962;14705.632359190999;14730;14157.433702651882;14439.783403248959;14829.18439485828;14870.925645371812;15235;13242.933785331012;13263.204207747565;13372.398242079129;13637.318134093355;13788.586342409644;3984.721069525134;3988.202359528106;3991.7968574579227;4038.5557588665224;4162.771047592381;83868;84081.57959210227;85772;85916;88022;11443.141764367705;11480.540778368771;11490.94254528722;11696.064314854792;12191.87808121925;22054.88972555127;22079;22085.558288834134;22086.558268834855;22213.777862221494;7954.244346787046;8073.425681991956;8195.7951051223;8292.917070829215;8810.911890881138;4546.499885012646;4595.862124136282;4641.164590373765;4704.835330763407;4719.339292499053;3134.905952821419;3212.100611828692;3215.565898603705;3217.533457648628;3221.5328777327154;554.0636324611394;561.1890817768285;576.345847463124;586.2466730251606;586.4223739616474;542.785599688121;624.6501958903029;635.5106567942659;673.5921923180576;706.9929300706928;9416.152546270869;9759;9781.755456113655;10144.492775361203;10182.745431364276;623.8689875126183;634.5462993959248;641.0960545630638;652.1489456259627;684.4763755726873;4263.658907287471;4792.928106078341;4850.1269771441475;4937.241817604027;4958;120840.39579802044;122148.38925805314;124418;126939;128462;104957;105016;106120;107811.46094269636;108988.4550577242;92070.99999999866;92435.53782231046;94109.5294523523;96157.5192124035;98210.5089474548;8843.69047083349;8951.73144805657;8954.865677014981;8995.685151019683;9005.279577633904;54618.45381546134;54683.179752304546;54940.17589736077;55394;55789.72105139448;76396.94412223449;77853.61073194824;81171.59414202892;81609;85154;11995.880041199476;12003.399830008639;12099.153059285765;12267.325297108744;12298.20061695987;13090.280034597808;13178.472861085449;13833.170009799444;13987.880969522617;14019.985703073888;11664.183507154758;12000.579979701019;12220.755584888075;12275.631731048083;12331.753364932472;11822.645320640395;12200.14598978063;12228.266304021787;12245.57140500114;12315.44580493862;12365.381730913597;12387.75224495523;12413.131080824256;12453.688657783265;12738.363081846055;8392.244697977212;8768.956155219183;10192.592296308065;10432.791344173225;10520.947395262974;9044.50255235942;9462.952685236529;9652.517374131407;9697.903020969983;9721.9027809721 10:58:27 INFO - PROCESS | 9811 | __end_tp_report 10:58:27 INFO - PROCESS | 9811 | __start_cc_report 10:58:27 INFO - PROCESS | 9811 | _x_x_mozilla_cycle_collect,1431 10:58:27 INFO - PROCESS | 9811 | __end_cc_report 10:58:27 INFO - PROCESS | 9811 | __startTimestamp1474307907774__endTimestamp 10:58:27 INFO - PROCESS | 9811 | 10:58:27 INFO - PROCESS | 9811 | ------- Summary: start ------- 10:58:27 INFO - PROCESS | 9811 | Number of tests: 6 10:58:27 INFO - PROCESS | 9811 | 10:58:27 INFO - PROCESS | 9811 | [#0] dojo.html Cycles:165 Average:5121.26 Median:5299.89 stddev:552.87 (10.4%) stddev-sans-first:526.38 10:58:27 INFO - PROCESS | 9811 | Values: 2899.8 2984.4 2991.5 3003.9 3033.2 4987.8 5086.6 5157.8 5222.1 5237.0 5123.0 5141.7 5250.1 5265.6 5347.8 4669.0 4710.4 4723.1 4804.4 4829.9 4691.4 4749.6 4750.9 4755.4 4812.9 4713.5 4749.0 4752.9 4757.8 4819.6 4624.4 4639.9 4651.6 4693.9 4776.0 4660.9 4662.0 4671.9 4732.2 4754.4 5062.5 5111.2 5114.6 5117.7 5156.6 5384.0 5437.9 5456.0 5467.4 5490.9 5549.5 5575.9 5590.7 5650.5 5654.8 5199.0 5211.6 5211.8 5214.5 5263.1 5506.2 5571.9 5709.5 5716.7 5718.1 5287.9 5339.3 5376.1 5423.0 5433.6 5372.0 5412.3 5467.9 5480.5 5485.6 5393.0 5398.7 5400.3 5401.2 5402.6 5291.0 5394.7 5405.3 5426.2 5465.8 4292.1 4297.3 4345.9 4347.4 4394.5 3851.2 3858.9 3868.0 3889.6 3911.0 5288.8 5292.1 5295.3 5296.2 5297.7 5260.7 5268.3 5441.8 5516.3 5535.9 5111.6 5116.9 5121.3 5132.2 5179.2 4775.3 4777.8 4782.0 4786.1 4793.8 5582.5 5587.7 5633.4 5640.1 5651.3 5394.3 5447.7 5449.8 5507.8 5514.8 5624.2 5629.0 5631.2 5632.5 5636.4 5296.5 5304.4 5362.6 5368.9 5372.0 5534.9 5547.8 5551.9 5553.6 5555.0 5349.8 5385.7 5392.5 5401.9 5405.8 5343.8 5406.6 5427.0 5498.9 5502.8 5367.9 5370.2 5372.7 5378.2 5422.0 5454.3 5460.1 5460.7 5467.3 5533.7 5215.8 5254.8 5261.8 5299.9 5348.9 10:58:27 INFO - PROCESS | 9811 | 10:58:27 INFO - PROCESS | 9811 | [#1] ext.html Cycles:180 Average:55377.73 Median:36284.90 stddev:102661.05 (282.9%) stddev-sans-first:102866.51 10:58:27 INFO - PROCESS | 9811 | Values: 550.2 585.4 608.2 609.2 626.3 32282.2 43174.6 44139.3 44149.6 44260.6 46215.8 46328.3 46589.3 46669.8 46703.3 8121.9 8988.2 9058.3 9081.8 9139.9 7963.8 10656.4 10863.5 10896.6 11273.2 11057.0 11114.4 11124.6 11177.6 11183.7 10879.9 11052.1 11083.4 11087.4 11089.6 10656.3 10739.1 10745.0 10750.9 10760.6 43354.8 43566.1 43909.1 44087.0 44128.6 3478.6 3485.6 3919.5 4046.6 4182.1 31371.5 31953.0 31971.0 31995.0 32113.0 4279.0 4385.8 4483.1 4642.1 4685.8 321185.4 325751.0 326860.0 327141.0 329440.0 52324.0 52845.5 52893.2 52897.2 52953.7 62292.0 62301.7 62363.0 62471.7 62531.2 16545.7 16636.3 16766.4 16788.2 16793.0 11652.4 11876.4 12490.3 12823.9 12945.5 16631.2 16901.4 16966.0 17154.8 17177.1 6395.6 6463.6 6517.8 6652.5 6732.3 981.4 987.2 1066.7 1123.9 1145.2 31967.7 32061.8 32171.5 32185.2 32566.0 1074.8 1108.5 1108.8 1122.5 1167.2 16756.5 16777.4 16914.2 16919.4 16970.7 497449.0 497830.0 498401.0 499795.5 500371.0 59659.0 59850.7 60076.4 60084.7 60108.0 1207.5 1238.8 1303.7 1350.8 1391.1 28401.3 32889.7 33074.5 33404.0 33662.8 50885.2 51810.5 51944.2 51985.5 52003.5 312476.0 313467.0 314841.0 314897.0 314971.0 27573.3 28618.1 28788.7 30491.0 41749.0 48175.8 48327.0 48361.5 48439.0 50088.0 39657.1 40015.6 40951.0 42202.6 42531.6 39536.4 40393.4 40785.0 41192.6 41781.0 39761.6 39871.8 40808.8 41084.6 41264.0 32905.5 36122.0 36447.8 36497.0 36950.6 26998.5 27118.9 27165.3 27482.5 27612.6 10:58:27 INFO - PROCESS | 9811 | 10:58:27 INFO - PROCESS | 9811 | [#2] jquery.html Cycles:180 Average:28158.57 Median:1683.65 stddev:75559.49 (4487.8%) stddev-sans-first:75742.66 10:58:27 INFO - PROCESS | 9811 | Values: 374.4 398.1 409.7 411.8 413.4 13774.4 15706.1 19496.7 20013.0 20285.9 19392.7 19672.3 19865.3 19938.6 20000.6 5682.8 5711.0 5844.8 5928.9 5931.3 5790.0 5793.4 5825.4 5832.2 5834.2 4094.5 4296.1 6188.2 6399.4 6454.7 6186.4 6309.0 6514.2 6522.4 6540.5 5548.6 5579.0 5583.1 5587.9 5591.6 22969.9 23719.1 23770.2 23789.0 23961.6 2493.7 2529.6 2561.7 2672.4 2993.0 14199.9 14847.1 14986.3 15143.3 15181.0 1906.1 1914.3 1937.2 1970.9 1993.9 82921.6 84470.0 85303.6 86482.6 87909.6 10589.7 10687.5 10833.6 11012.8 11190.1 15552.5 15560.9 15611.1 15664.8 15693.6 7996.3 8132.6 8385.5 8523.1 8557.2 7984.0 7989.8 8037.2 8234.5 8239.3 6617.2 6746.8 6759.5 6772.5 6838.9 1934.4 1993.5 2000.1 2022.8 2036.4 909.4 991.7 992.4 1039.8 1040.9 18680.0 19708.4 20137.1 20315.9 20382.7 973.1 975.1 980.5 991.8 999.9 9874.8 11270.0 11328.2 11419.8 11456.8 446345.0 447538.0 450944.0 471823.0 473129.0 51774.2 86618.6 87732.1 89296.6 89443.6 1007.2 1037.2 1046.0 1078.2 1078.8 17245.9 17742.3 18057.4 18065.9 18142.3 40811.8 41793.6 41952.8 41994.4 42761.0 55219.0 56734.7 58245.4 58800.7 59408.7 8019.8 8060.4 8164.8 8219.1 8500.6 13084.3 13106.5 13513.3 13806.9 14255.3 11018.4 11073.4 11536.4 11918.1 12000.3 11100.6 11658.2 11669.9 11765.5 12351.9 10985.5 11348.8 11408.9 11553.1 12095.8 8020.4 8199.3 8273.1 8287.2 8384.6 10931.3 11300.2 11558.3 11622.9 11866.7 10:58:27 INFO - PROCESS | 9811 | 10:58:27 INFO - PROCESS | 9811 | [#3] mootools.html Cycles:180 Average:5572.61 Median:4686.64 stddev:3149.15 (67.2%) stddev-sans-first:3141.68 10:58:27 INFO - PROCESS | 9811 | Values: 1308.5 1460.4 1485.2 1617.2 1792.0 7390.8 8175.4 8413.7 8426.6 8687.3 8458.4 8524.1 8528.9 8535.4 8538.4 2348.7 2370.7 2371.1 2376.5 2379.9 2290.5 2345.0 2367.1 2373.3 2375.9 2343.9 2361.0 2366.7 2376.2 2382.9 2340.7 2356.0 2362.1 2367.1 2377.7 2243.0 2310.9 2321.8 2322.3 2327.7 7404.4 7598.2 8419.9 8448.5 8476.7 4528.4 4650.5 4696.6 4800.0 4850.0 3055.2 3216.9 3286.6 3294.0 3295.1 1097.7 1104.3 1107.8 1119.7 1121.6 11683.3 11734.4 11743.5 11750.8 11808.2 4189.0 4314.8 4484.8 4490.8 4653.3 9887.9 9888.9 9901.7 9902.8 9932.5 4991.0 5006.8 5009.2 5009.2 5022.4 4479.7 4572.6 4592.0 4595.8 4609.7 3842.1 3873.9 3939.7 3947.9 4035.0 1284.1 1299.8 1308.6 1319.6 1322.3 766.7 907.9 924.4 924.9 967.3 5597.7 5640.9 5757.9 5817.8 5842.5 916.2 928.8 951.1 993.4 1018.3 3881.2 3937.6 4022.8 4030.7 4039.3 9949.0 10724.9 10796.1 10827.9 10829.0 10447.7 10711.5 10728.8 10751.5 10756.7 7717.0 7810.4 7869.8 7885.6 7888.4 5626.2 5646.2 5661.7 5768.1 5838.9 9467.3 9486.7 9534.1 9535.7 9639.0 9206.8 9467.1 9829.3 9840.5 9943.8 7619.0 7686.3 7702.9 7708.4 7743.4 8206.5 8293.0 8298.5 8315.4 8331.7 7165.7 7321.1 7514.0 7544.4 7621.0 7131.4 7307.5 7379.8 7606.6 7628.2 6913.9 6986.3 6989.5 7008.2 7056.9 4174.6 4533.7 4600.4 4613.9 4669.5 4277.7 4633.2 4648.6 4676.7 5009.1 10:58:27 INFO - PROCESS | 9811 | 10:58:27 INFO - PROCESS | 9811 | [#4] prototype.html Cycles:180 Average:4679.22 Median:5333.31 stddev:1373.86 (25.8%) stddev-sans-first:1337.99 10:58:27 INFO - PROCESS | 9811 | Values: 309.0 317.2 325.4 329.9 338.5 5159.2 5240.5 5349.8 5363.0 5377.9 5098.9 5281.8 5309.3 5313.9 5332.7 4146.0 4179.2 4187.9 4190.9 4210.5 3982.8 4075.9 4101.5 4182.2 4183.9 4111.2 4124.5 4145.0 4146.4 4158.6 4130.1 4149.8 4169.9 4174.6 4190.9 3495.0 3617.5 3703.4 3704.0 3705.6 5124.5 5137.7 5143.4 5157.8 5171.8 4667.8 4694.5 4696.5 4712.2 4775.0 5232.3 5460.9 5575.5 5594.4 5614.2 3913.9 3918.8 3931.4 3953.8 3959.5 5828.2 5845.4 5846.3 5850.6 5864.4 3980.7 4111.7 4124.3 4130.9 4141.9 4097.7 4112.4 4140.2 4141.6 4151.8 4396.8 4530.0 4567.7 4698.4 4722.5 5540.6 5552.9 5590.8 5625.6 5643.5 1415.7 1424.5 1427.6 1428.5 1434.8 934.8 972.3 977.7 984.6 989.1 5275.2 5285.7 5293.9 5294.0 5412.7 5631.9 5650.5 5650.9 5682.9 5715.1 5258.4 5327.9 5333.9 5337.3 5378.4 2951.4 2982.2 2999.1 3002.0 3013.4 5620.2 5766.7 5820.6 5827.9 5883.6 5724.1 5726.8 5737.5 5738.4 5752.5 5823.9 5834.5 5849.5 5858.2 5881.8 5464.4 5644.0 5741.9 5741.9 5753.8 5636.4 5681.8 5683.6 5727.1 5727.5 5734.0 5840.9 5927.2 6017.2 6025.9 4065.8 4073.9 4104.5 4118.6 4124.4 5497.2 5581.2 5655.9 5789.4 5813.3 5493.8 5552.8 5562.2 5580.0 5609.6 5530.2 5547.9 5557.3 5587.6 5597.5 5240.6 5479.4 5503.8 5513.1 5518.9 5595.9 5606.2 5611.7 5674.2 5682.8 5293.9 5491.0 5543.9 5553.3 5560.9 10:58:27 INFO - PROCESS | 9811 | 10:58:27 INFO - PROCESS | 9811 | [#5] yui.html Cycles:180 Average:21831.98 Median:4756.13 stddev:32797.45 (689.6%) stddev-sans-first:32851.24 10:58:27 INFO - PROCESS | 9811 | Values: 744.8 762.4 765.4 823.9 875.2 14446.1 15394.8 15443.4 15450.8 15468.6 14739.3 15414.9 15565.2 15578.7 15657.9 804.2 822.1 824.0 826.4 826.4 648.4 668.7 797.3 819.3 824.6 871.5 874.3 874.5 874.7 874.9 842.9 860.2 863.1 863.3 863.3 500.9 508.5 607.0 620.1 625.5 14666.3 14676.0 14698.9 14705.6 14730.0 14157.4 14439.8 14829.2 14870.9 15235.0 13242.9 13263.2 13372.4 13637.3 13788.6 3984.7 3988.2 3991.8 4038.6 4162.8 83868.0 84081.6 85772.0 85916.0 88022.0 11443.1 11480.5 11490.9 11696.1 12191.9 22054.9 22079.0 22085.6 22086.6 22213.8 7954.2 8073.4 8195.8 8292.9 8810.9 4546.5 4595.9 4641.2 4704.8 4719.3 3134.9 3212.1 3215.6 3217.5 3221.5 554.1 561.2 576.3 586.2 586.4 542.8 624.7 635.5 673.6 707.0 9416.2 9759.0 9781.8 10144.5 10182.7 623.9 634.5 641.1 652.1 684.5 4263.7 4792.9 4850.1 4937.2 4958.0 120840.4 122148.4 124418.0 126939.0 128462.0 104957.0 105016.0 106120.0 107811.5 108988.5 92071.0 92435.5 94109.5 96157.5 98210.5 8843.7 8951.7 8954.9 8995.7 9005.3 54618.5 54683.2 54940.2 55394.0 55789.7 76396.9 77853.6 81171.6 81609.0 85154.0 11995.9 12003.4 12099.2 12267.3 12298.2 13090.3 13178.5 13833.2 13987.9 14020.0 11664.2 12000.6 12220.8 12275.6 12331.8 11822.6 12200.1 12228.3 12245.6 12315.4 12365.4 12387.8 12413.1 12453.7 12738.4 8392.2 8769.0 10192.6 10432.8 10520.9 9044.5 9463.0 9652.5 9697.9 9721.9 10:58:27 INFO - PROCESS | 9811 | -------- Summary: end -------- 10:58:27 INFO - PROCESS | 9811 | 10:58:28 INFO - TEST-INFO | 9811: exit 0 10:58:28 INFO - TEST-OK | dromaeo_css | took 1116052ms 10:58:28 INFO - TEST-START | kraken 10:58:28 INFO - operating with platform_type : linux_ 10:58:28 INFO - Initialising browser for kraken test... 10:58:28 INFO - TEST-INFO | started process 10118 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2IO07P/profile http://localhost:53571/getInfo.html) 10:58:32 INFO - PROCESS | 10118 | __metrics Screen width/height:1600/1200 10:58:32 INFO - PROCESS | 10118 | colorDepth:24 10:58:32 INFO - PROCESS | 10118 | Browser inner width/height: 1024/697 10:58:32 INFO - PROCESS | 10118 | __metrics 10:58:33 INFO - PROCESS | 10118 | 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" 10:58:33 INFO - PROCESS | 10118 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:58:33 INFO - PROCESS | 10118 | console.error: PushService: 10:58:33 INFO - PROCESS | 10118 | stateChangeProcessEnqueue: Error transitioning state 10:58:33 INFO - PROCESS | 10118 | UnknownError 10:58:34 INFO - TEST-INFO | 10118: exit 0 10:58:34 INFO - Browser initialized. 10:58:34 INFO - Running cycle 1/1 for kraken test... 10:58:34 INFO - TEST-INFO | started process 10202 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2IO07P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:58:46 INFO - PROCESS | 10202 | RSS: Main: 172769280 10:58:46 INFO - PROCESS | 10202 | 10:58:49 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53571/tests/kraken/driver.html?testName=audio-beat-detection) 10:58:49 INFO - PROCESS | 10202 | RSS: Main: 240787456 10:58:49 INFO - PROCESS | 10202 | 10:58:52 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53571/tests/kraken/driver.html?testName=audio-dft) 10:58:53 INFO - PROCESS | 10202 | RSS: Main: 216346624 10:58:53 INFO - PROCESS | 10202 | 10:58:56 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53571/tests/kraken/driver.html?testName=audio-fft) 10:58:56 INFO - PROCESS | 10202 | RSS: Main: 297811968 10:58:56 INFO - PROCESS | 10202 | 10:58:58 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53571/tests/kraken/driver.html?testName=audio-oscillator) 10:58:59 INFO - PROCESS | 10202 | RSS: Main: 207646720 10:58:59 INFO - PROCESS | 10202 | 10:59:01 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53571/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:59:02 INFO - PROCESS | 10202 | RSS: Main: 211382272 10:59:02 INFO - PROCESS | 10202 | 10:59:05 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53571/tests/kraken/driver.html?testName=imaging-darkroom) 10:59:05 INFO - PROCESS | 10202 | RSS: Main: 248950784 10:59:05 INFO - PROCESS | 10202 | 10:59:09 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53571/tests/kraken/driver.html?testName=imaging-desaturate) 10:59:09 INFO - PROCESS | 10202 | RSS: Main: 247595008 10:59:09 INFO - PROCESS | 10202 | 10:59:12 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53571/tests/kraken/driver.html?testName=json-parse-financial) 10:59:13 INFO - PROCESS | 10202 | RSS: Main: 247779328 10:59:13 INFO - PROCESS | 10202 | 10:59:14 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53571/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:59:15 INFO - PROCESS | 10202 | RSS: Main: 210087936 10:59:15 INFO - PROCESS | 10202 | 10:59:16 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53571/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:59:17 INFO - PROCESS | 10202 | RSS: Main: 218357760 10:59:17 INFO - PROCESS | 10202 | 10:59:19 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53571/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:59:20 INFO - PROCESS | 10202 | RSS: Main: 222650368 10:59:20 INFO - PROCESS | 10202 | 10:59:22 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53571/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:59:23 INFO - PROCESS | 10202 | RSS: Main: 223162368 10:59:23 INFO - PROCESS | 10202 | 10:59:26 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53571/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:59:26 INFO - PROCESS | 10202 | RSS: Main: 221597696 10:59:26 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | Cycle 1(1): loaded http://localhost:53571/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53571/tests/kraken/driver.html?testName=ai-astar) 10:59:28 INFO - PROCESS | 10202 | RSS: Main: 271966208 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | __start_tp_report 10:59:28 INFO - PROCESS | 10202 | _x_x_mozilla_page_load 10:59:28 INFO - PROCESS | 10202 | _x_x_mozilla_page_load_details 10:59:28 INFO - PROCESS | 10202 | |i|pagename|runs| 10:59:28 INFO - PROCESS | 10202 | |0;ai-astar;111;114;113;111;112;113;113;111;111;111 10:59:28 INFO - PROCESS | 10202 | |1;audio-beat-detection;145;122;118;165;124;120;118;125;157;125 10:59:28 INFO - PROCESS | 10202 | |2;audio-dft;161;161;160;160;159;160;161;161;161;161 10:59:28 INFO - PROCESS | 10202 | |3;audio-fft;73;75;76;107;78;77;76;75;109;74 10:59:28 INFO - PROCESS | 10202 | |4;audio-oscillator;82;80;112;82;82;82;105;82;81;124 10:59:28 INFO - PROCESS | 10202 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;116;117 10:59:28 INFO - PROCESS | 10202 | |6;imaging-darkroom;145;147;145;146;146;147;145;146;146;146 10:59:28 INFO - PROCESS | 10202 | |7;imaging-desaturate;96;95;96;96;95;96;95;95;95;95 10:59:28 INFO - PROCESS | 10202 | |8;json-parse-financial;49;50;49;48;50;51;52;51;51;50 10:59:28 INFO - PROCESS | 10202 | |9;json-stringify-tinderbox;61;61;61;61;62;61;61;61;62;60 10:59:28 INFO - PROCESS | 10202 | |10;stanford-crypto-aes;79;79;80;79;79;79;79;78;80;78 10:59:28 INFO - PROCESS | 10202 | |11;stanford-crypto-ccm;123;138;124;128;124;122;123;124;164;124 10:59:28 INFO - PROCESS | 10202 | |12;stanford-crypto-pbkdf2;162;165;164;162;165;165;166;167;164;167 10:59:28 INFO - PROCESS | 10202 | |13;stanford-crypto-sha256-iterative;65;65;66;64;64;64;69;64;62;64 10:59:28 INFO - PROCESS | 10202 | __end_tp_report 10:59:28 INFO - PROCESS | 10202 | __start_cc_report 10:59:28 INFO - PROCESS | 10202 | _x_x_mozilla_cycle_collect,1659 10:59:28 INFO - PROCESS | 10202 | __end_cc_report 10:59:28 INFO - PROCESS | 10202 | __startTimestamp1474307968776__endTimestamp 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | ------- Summary: start ------- 10:59:28 INFO - PROCESS | 10202 | Number of tests: 14 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:1.15 (1.0%) stddev-sans-first:1.17 10:59:28 INFO - PROCESS | 10202 | Values: 111.0 114.0 113.0 111.0 112.0 113.0 113.0 111.0 111.0 111.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:125.00 stddev:17.26 (13.8%) stddev-sans-first:17.64 10:59:28 INFO - PROCESS | 10202 | Values: 145.0 122.0 118.0 165.0 124.0 120.0 118.0 125.0 157.0 125.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:0.71 (0.4%) stddev-sans-first:0.73 10:59:28 INFO - PROCESS | 10202 | Values: 161.0 161.0 160.0 160.0 159.0 160.0 161.0 161.0 161.0 161.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.50 stddev:13.78 (18.3%) stddev-sans-first:14.23 10:59:28 INFO - PROCESS | 10202 | Values: 73.0 75.0 76.0 107.0 78.0 77.0 76.0 75.0 109.0 74.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:82.00 stddev:16.16 (19.7%) stddev-sans-first:16.80 10:59:28 INFO - PROCESS | 10202 | Values: 82.0 80.0 112.0 82.0 82.0 82.0 105.0 82.0 81.0 124.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:59:28 INFO - PROCESS | 10202 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 10:59:28 INFO - PROCESS | 10202 | Values: 145.0 147.0 145.0 146.0 146.0 147.0 145.0 146.0 146.0 146.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 10:59:28 INFO - PROCESS | 10202 | Values: 96.0 95.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.50 stddev:1.20 (2.4%) stddev-sans-first:1.20 10:59:28 INFO - PROCESS | 10202 | Values: 49.0 50.0 49.0 48.0 50.0 51.0 52.0 51.0 51.0 50.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:61.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 10:59:28 INFO - PROCESS | 10202 | Values: 61.0 61.0 61.0 61.0 62.0 61.0 61.0 61.0 62.0 60.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 10:59:28 INFO - PROCESS | 10202 | Values: 79.0 79.0 80.0 79.0 79.0 79.0 79.0 78.0 80.0 78.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#11] stanford-crypto-ccm Cycles:10 Average:129.40 Median:124.00 stddev:13.02 (10.5%) stddev-sans-first:13.61 10:59:28 INFO - PROCESS | 10202 | Values: 123.0 138.0 124.0 128.0 124.0 122.0 123.0 124.0 164.0 124.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:165.00 stddev:1.77 (1.1%) stddev-sans-first:1.58 10:59:28 INFO - PROCESS | 10202 | Values: 162.0 165.0 164.0 162.0 165.0 165.0 166.0 167.0 164.0 167.0 10:59:28 INFO - PROCESS | 10202 | 10:59:28 INFO - PROCESS | 10202 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:64.50 stddev:1.83 (2.8%) stddev-sans-first:1.94 10:59:28 INFO - PROCESS | 10202 | Values: 65.0 65.0 66.0 64.0 64.0 64.0 69.0 64.0 62.0 64.0 10:59:28 INFO - PROCESS | 10202 | -------- Summary: end -------- 10:59:28 INFO - PROCESS | 10202 | 10:59:29 INFO - TEST-INFO | 10202: exit 0 10:59:30 INFO - TEST-OK | kraken | took 61292ms 10:59:30 INFO - SUITE-END | took 1177s 10:59:30 INFO - Completed test suite (00:19:37) 10:59:30 INFO - javascript benchmark 10:59:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2899.826010439374, 2984.3583629519662, 2991.506401443762, 3003.9399212015746, 3033.1658793831693, 4987.800487980477, 5086.593072554196, 5157.845264642062, 5222.060029194743, 5237.031149237394, 5123.0, 5141.717205553693, 5250.133727934886, 5265.578753699704, 5347.786088556462, 4669.042846216517, 4710.387649605546, 4723.10261050401, 4804.351412559304, 4829.90340193196, 4691.437027556698, 4749.62003039756, 4750.881227969311, 4755.381800365947, 4812.927806082894, 4713.481517033124, 4749.026449577828, 4752.881177970544, 4757.809687612508, 4819.566239038486, 4624.352590637313, 4639.86080417588, 4651.604613607848, 4693.929591056137, 4775.976120119377, 4660.883477913046, 4661.953380466186, 4671.85984420468, 4732.242841145435, 4754.429468463772, 5062.493750624908, 5111.156659151273, 5114.641975061747, 5117.744112794346, 5156.561692256163, 5384.030874442598, 5437.891242175174, 5455.990641731253, 5467.4403610573745, 5490.945090549083, 5549.5005449509335, 5575.944240557583, 5590.748416321276, 5650.51970582501, 5654.773809047633, 5199.0381779370555, 5211.635185536976, 5211.765470553834, 5214.45248248936, 5263.105272103751, 5506.174073888868, 5571.9442805572235, 5709.486146246858, 5716.657000579979, 5718.142278658234, 5287.920681189786, 5339.279197308314, 5376.1398176291605, 5422.972885135549, 5433.6196466247775, 5371.973140134274, 5412.296401467784, 5467.9453205467435, 5480.479354461319, 5485.588580856452, 5393.0, 5398.73006349681, 5400.297961265089, 5401.243825864382, 5402.567794576425, 5291.04761142998, 5394.676319420769, 5405.270288511001, 5426.186072089139, 5465.836024919259, 4292.098659281523, 4297.290946993773, 4345.9348109778375, 4347.413099231563, 4394.472663280363, 3851.2105018471148, 3858.9421158682644, 3868.0, 3889.552701439314, 3911.02224443889, 5288.788448462018, 5292.100342941709, 5295.285136506599, 5296.152615581488, 5297.682139071668, 5260.684358938476, 5268.28878101459, 5441.83674489766, 5516.3380394352935, 5535.889282214333, 5111.616628752859, 5116.923246151311, 5121.308623335876, 5132.204508301219, 5179.249008893765, 4775.259834725553, 4777.8327758528285, 4781.976090119527, 4786.090642777861, 4793.760311984387, 5582.525485333792, 5587.664740115606, 5633.380328163898, 5640.097584386478, 5651.321841378978, 5394.298741163623, 5447.719785850243, 5449.809256675997, 5507.807226747044, 5514.80698175578, 5624.240727501735, 5629.043062679436, 5631.183478395692, 5632.521235694848, 5636.351819540805, 5296.470352964827, 5304.416514183359, 5362.624616276823, 5368.865973200692, 5372.006178856924, 5534.861628459321, 5547.750351234285, 5551.944480555143, 5553.611247212818, 5554.972225138848, 5349.839504814861, 5385.703786291635, 5392.5416339611575, 5401.864953376197, 5405.78376864921, 5343.7862485500145, 5406.621536492407, 5427.023135835588, 5498.890022199614, 5502.834914952397, 5367.919481207707, 5370.248165256945, 5372.704501252469, 5378.220158077135, 5422.024035673617, 5454.318210223722, 5460.126379779216, 5460.726963651881, 5467.261919640798, 5533.667979921218, 5215.843524694265, 5254.816081437131, 5261.7895284189735, 5299.894002119859, 5348.9465105348445], "value": 5085.464858678725, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.2226620484909, 585.4087371754528, 608.1546650156281, 609.1563184988789, 626.3423405424303, 32282.192945176383, 43174.56825431745, 44139.337909931375, 44149.558504414985, 44260.55739442605, 46215.76892115543, 46328.305075423894, 46589.30116048262, 46669.766651166785, 46703.29945050819, 8121.918780812205, 8988.235999940012, 9058.320625953047, 9081.818363632723, 9139.862902056475, 7963.840723185533, 10656.413897235649, 10863.51114199859, 10896.564137434492, 11273.210875238736, 11057.0, 11114.388708621063, 11124.61063862765, 11177.608783692573, 11183.664490065312, 10879.945600271987, 11052.060574851066, 11083.445827708581, 11087.445627718662, 11089.55641774332, 10656.307340022873, 10739.140868730561, 10745.0, 10750.892491075067, 10760.569577216902, 43354.783226083986, 43566.12867742659, 43909.12181756347, 44087.0, 44128.558714412764, 3478.565179352581, 3485.581730192393, 3919.4708714323488, 4046.6358027777346, 4182.079942412694, 31371.529427058613, 31953.0, 31971.0, 31995.0, 32113.0, 4278.978605106954, 4385.758783266919, 4483.080968401502, 4642.071585682843, 4685.812567497296, 321185.3940730305, 325751.0, 326860.0, 327141.0, 329440.0, 52324.0, 52845.471545284825, 52893.206601901, 52897.206541901905, 52953.67615809636, 62292.0, 62301.68849155725, 62363.0, 62471.687641562414, 62531.24812511264, 16545.6690866182, 16636.33454661824, 16766.413175538797, 16788.160591970354, 16793.0, 11652.41737913101, 11876.406179690981, 12490.250584964931, 12823.871761282267, 12945.54690585825, 16631.168441577873, 16901.408450704166, 16966.0, 17154.828451715573, 17177.141142942804, 6395.6482393468805, 6463.57986730861, 6517.770750830398, 6652.467802575782, 6732.259451460335, 981.40134517944, 987.2151639446657, 1066.7279843639822, 1123.8988491035682, 1145.1869172887343, 31967.680323196935, 32061.839690801862, 32171.51742723861, 32185.195370115933, 32566.0, 1074.8172810622214, 1108.4521967552348, 1108.8225883858706, 1122.45560902962, 1167.235460773195, 16756.497305080866, 16777.412790552273, 16914.154292285337, 16919.407820726214, 16970.660586788195, 497449.0, 497830.0, 498401.0, 499795.53169258824, 500371.0, 59659.0, 59850.70074649599, 60076.39923600796, 60084.69957650184, 60108.0, 1207.4687137659403, 1238.7894058009979, 1303.680598253396, 1350.770369037266, 1391.0679844504207, 28401.28996775097, 32889.67110328866, 33074.503882442266, 33404.0, 33662.83168584141, 50885.236721449946, 51810.481895180565, 51944.22083668823, 51985.48014519807, 52003.47996519986, 312476.0, 313467.0, 314841.0, 314897.0, 314971.0, 27573.31066723348, 28618.141455756362, 28788.712112878602, 30491.0, 41749.0, 48175.759121204166, 48327.0, 48361.51638483711, 48439.0, 50088.0, 39657.12000799697, 40015.599844002354, 40951.0, 42202.577974219865, 42531.57468425276, 39536.40695389629, 40393.39409908795, 40785.0, 41192.588074118874, 41781.0, 39761.60238397695, 39871.80064099661, 40808.79595602003, 41084.58915410927, 41264.0, 32905.50641740328, 36122.0, 36447.8034955121, 36497.0, 36950.63049369472, 26998.460030799666, 27118.864405677847, 27165.320866978484, 27482.547513707093, 27612.585811212448], "value": 20262.81193541548, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.35423893783246, 398.13803116253314, 409.66202882621843, 411.8085090432948, 413.39644119585404, 13774.449022039118, 15706.136162511057, 19496.707549386734, 20013.0, 20285.898570507165, 19392.70910936337, 19672.326127679815, 19865.304714335, 19938.601227975432, 20000.59998800023, 5682.801101961432, 5711.0, 5844.766209351621, 5928.851778705523, 5931.258592675916, 5790.015697331463, 5793.449622285876, 5825.388334224909, 5832.241808564881, 5834.241548598677, 4094.488188976378, 4296.076343586128, 6188.195534580498, 6399.424051835334, 6454.709538070798, 6186.443220110212, 6309.0, 6514.21829380475, 6522.445592124652, 6540.476761859064, 5548.58385621076, 5579.023670857584, 5583.078791999315, 5587.916181257285, 5591.552675785927, 22969.88515057414, 23719.05123795046, 23770.168044118545, 23789.0, 23961.640575391382, 2493.7256901740793, 2529.582618867903, 2561.6541766861424, 2672.3853513691765, 2992.9524666366597, 14199.929000355036, 14847.109173449628, 14986.250687465583, 15143.318550665137, 15181.0, 1906.075553356621, 1914.2630087416305, 1937.18084498077, 1970.9211631534722, 1993.8703984240979, 82921.58539207265, 84470.0, 85303.57348213281, 86482.5675871623, 87909.56045219733, 10589.652225626009, 10687.465626718633, 10833.620823271305, 11012.779744405227, 11190.104791616652, 15552.45566405193, 15560.922195389177, 15611.141387223812, 15664.843351566338, 15693.607659808367, 7996.280334769898, 8132.634031468599, 8385.454945428528, 8523.06246312905, 8557.187067228604, 7984.0, 7989.800254993672, 8037.196280372033, 8234.547099909443, 8239.258466738022, 6617.205935287796, 6746.83132921671, 6759.493038022069, 6772.492063095189, 6838.897416538756, 1934.3906669399094, 1993.5016245938516, 2000.1099510717881, 2022.7977202279653, 2036.409441262047, 909.3725329522608, 991.7024892532212, 992.3549692699803, 1039.7660526381503, 1040.9011143941314, 18680.0, 19708.40874773759, 20137.09383077765, 20315.8984205081, 20382.7196288781, 973.0755782007121, 975.0834215837091, 980.5097451274363, 991.7570195302197, 999.9100080992746, 9874.753131171778, 11270.0, 11328.150388720878, 11419.771604567863, 11456.770864582662, 446345.0, 447538.0, 450944.0, 471823.0, 473129.0, 51774.22338664923, 86618.56690716506, 87732.12267877367, 89296.553517232, 89443.55278223698, 1007.214372789211, 1037.232447988483, 1045.9738506537396, 1078.1536493852445, 1078.8435676826973, 17245.91245582581, 17742.29030838752, 18057.367992120213, 18065.909670451565, 18142.32911241052, 40811.79594102011, 41793.58206417897, 41952.790236048626, 41994.37008444815, 42761.0, 55219.0, 56734.71632641811, 58245.37895794784, 58800.70599646975, 59408.70295648494, 8019.75693767467, 8060.39547033963, 8164.83670326602, 8219.095899450936, 8500.57497125129, 13084.345782711016, 13106.541271055466, 13513.256770877697, 13806.93096534511, 14255.287235638383, 11018.39398833072, 11073.390963497084, 11536.36549989725, 11918.106142039554, 12000.279983200686, 11100.611478598534, 11658.18392712502, 11669.941650291694, 11765.52937882475, 12351.93824030874, 10985.450727463434, 11348.829767553329, 11408.94295528517, 11553.133514986577, 12095.81856272139, 8020.398470114881, 8199.303059240032, 8273.089960104264, 8287.171282871665, 8384.58077096155, 10931.344119352865, 11300.152488563555, 11558.30650160993, 11622.883771162518, 11866.703332416759], "value": 9452.834738903397, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1308.4504508106606, 1460.3793387810179, 1485.242526311581, 1617.1590772798145, 1791.9910400447995, 7390.778276651696, 8175.3868459865525, 8413.663453461853, 8426.578671066438, 8687.305015598753, 8458.365622578316, 8524.104968978245, 8528.91471085289, 8535.445196062266, 8538.359623028267, 2348.659444380564, 2370.7273663528745, 2371.0515793682493, 2376.5128148729555, 2379.8691071991034, 2290.5075408787106, 2345.038534200978, 2367.10050180931, 2373.347329484397, 2375.8930848111877, 2343.906243750248, 2361.0319768894756, 2366.6923299971068, 2376.192094687806, 2382.9157733231336, 2340.707411573553, 2355.9882200588972, 2362.126013375054, 2367.1241640593003, 2377.690900182965, 2242.998074594569, 2310.87290199039, 2321.8374713770045, 2322.3381336319235, 2327.674125622414, 7404.370628496585, 7598.20218877021, 8419.915800841974, 8448.493090414595, 8476.703315383993, 4528.43394575678, 4650.488446270908, 4696.553827386393, 4799.952000479985, 4849.951500484985, 3055.1751027222526, 3216.903492895217, 3286.5891763529557, 3293.950590741141, 3295.0773783340464, 1097.6871591596437, 1104.2711810205226, 1107.7729065541544, 1119.7088756923176, 1121.6130434999911, 11683.299002059903, 11734.354610496419, 11743.471543780464, 11750.764984700429, 11808.173427860052, 4188.979055104705, 4314.848980285675, 4484.798184081724, 4490.753008584494, 4653.325267836145, 9887.851682224773, 9888.851667224997, 9901.653442129491, 9902.752431189134, 9932.453715045598, 4991.0, 5006.799728010911, 5009.223570346602, 5009.223570346602, 5022.372203474566, 4479.686421950498, 4572.634189264851, 4591.977040114778, 4595.839145629887, 4609.723416595015, 3842.077901303667, 3873.9418908716393, 3939.684825213976, 3947.940780888289, 4035.0, 1284.069049938787, 1299.811527328532, 1308.5681725030718, 1319.5843309357522, 1322.2661422910378, 766.6894907562446, 907.8865141857268, 924.4499522783933, 924.8658944453017, 967.3277072434731, 5597.6641401516035, 5640.85897852557, 5757.913631295534, 5817.767289308465, 5842.532597392198, 916.225789208118, 928.7910220200536, 951.0774548687762, 993.4138858073771, 1018.3177271228295, 3881.1849511602313, 3937.645611894943, 4022.8189731462153, 4030.6775457963295, 4039.333509970822, 9948.950255248823, 10724.892751072546, 10796.082333001626, 10827.891721082688, 10829.0, 10447.738806529898, 10711.517981690842, 10728.785424291627, 10751.462426878781, 10756.677299681021, 7717.0, 7810.414218933603, 7869.803254918673, 7885.566293853778, 7888.447808653454, 5626.156815847375, 5646.153077038477, 5661.660300381825, 5768.1347797828985, 5838.883222335445, 9467.337286389886, 9486.715398538056, 9534.141927226583, 9535.666251681158, 9638.95180524093, 9206.769830754285, 9467.147956683932, 9829.262805289774, 9840.458774767456, 9943.751406214902, 7618.961905190438, 7686.269804368576, 7702.884456733265, 7708.4218683600075, 7743.380529557735, 8206.548639824628, 8292.958535207285, 8298.460600060977, 8315.417920745731, 8331.666733330841, 7165.713371465083, 7321.053858708265, 7514.0, 7544.396448284234, 7621.047369078865, 7131.358177763818, 7307.525010874276, 7379.8155046124275, 7606.619669016416, 7628.237176282328, 6913.89629155553, 6986.266442023718, 6989.475789315923, 7008.194057683431, 7056.929430705627, 4174.580583092423, 4533.682642215013, 4600.355950166912, 4613.861584152481, 4669.4863565007145, 4277.721948073366, 4633.212353899778, 4648.581627653527, 4676.719396836201, 5009.148444764326], "value": 4489.607900795485, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.0265663161044, 317.1658538044942, 325.37040826001686, 329.8713501734324, 338.51223871086574, 5159.226116082585, 5240.475952404758, 5349.7592608332625, 5362.973185134074, 5377.946220537793, 5098.898022039557, 5281.815136470224, 5309.327561818025, 5313.910648317095, 5332.733363331828, 4145.979270103645, 4179.185058913506, 4187.937180942288, 4190.916181676364, 4210.536840947509, 3982.8406863725418, 4075.857344992926, 4101.528324242706, 4182.159849666153, 4183.937240941388, 4111.198316328318, 4124.505059392877, 4144.9585504145025, 4146.398772178032, 4158.6465150462245, 4130.112025914428, 4149.792510374469, 4169.874903752908, 4174.645155161815, 4190.874273771792, 3494.9825250873837, 3617.5297211362617, 3703.4444833274956, 3704.0369503928905, 3705.573859006222, 5124.461931497213, 5137.6660517066275, 5143.408508021588, 5157.8452646420665, 5171.7672704728375, 4667.836625718118, 4694.507076756926, 4696.506866779007, 4712.151812673717, 4774.9522504775205, 5232.293640358541, 5460.907818436329, 5575.52248654107, 5594.384617692051, 5614.203454894436, 3913.882583522499, 3918.7648741075627, 3931.410288456726, 3953.8418463261437, 3959.4852669152824, 5828.184054232411, 5845.41545845412, 5846.269216347999, 5850.64896106235, 5864.384239654817, 3980.7412518186225, 4111.650509706708, 4124.319487284624, 4130.876073717794, 4141.855035073758, 4097.651699605507, 4112.383142528644, 4140.15126898985, 4141.585841415835, 4151.813168407429, 4396.780160991938, 4529.954700452953, 4567.657425693086, 4698.412698412699, 4722.480527142012, 5540.584456165804, 5552.916706249329, 5590.804321848797, 5625.578081643893, 5643.548516118701, 1415.6531649745673, 1424.5370254667275, 1427.578864235053, 1428.457186269232, 1434.813474248341, 934.780326623243, 972.2610815780058, 977.731123940922, 984.5864736810556, 989.1394486796389, 5275.155975043975, 5285.709285989307, 5293.947060529423, 5293.994141113176, 5412.702301373462, 5631.8592035199445, 5650.513773939453, 5650.886982260414, 5682.920245153407, 5715.0853064936355, 5258.421573626898, 5327.920081198785, 5333.893322133536, 5337.306150200449, 5378.354597448331, 2951.394964032368, 2982.1799005273724, 2999.145243605562, 3001.9849900750355, 3013.367192889518, 5620.21799209394, 5766.682832444172, 5820.613548445202, 5827.912581311284, 5883.646981181143, 5724.109324790954, 5726.79956201531, 5737.512311453574, 5738.426157384228, 5752.453516915886, 5823.854403639858, 5834.474897259267, 5849.532037436995, 5858.150568167592, 5881.794137205177, 5464.371597266365, 5644.0, 5741.875506062784, 5741.885162296647, 5753.798617048383, 5636.408177141341, 5681.806820567562, 5683.602147849776, 5727.11229759388, 5727.455891690282, 5733.971330143323, 5840.926471102867, 5927.170196172453, 6017.18767966319, 6025.909611355745, 4065.837366505425, 4073.9185216294914, 4104.548499664975, 4118.567550407164, 4124.443200167939, 5497.230387745799, 5581.190727344593, 5655.915161272666, 5789.363170051207, 5813.331466881194, 5493.7802487900035, 5552.7778888843995, 5562.193481945176, 5580.0, 5609.579281553982, 5530.198121272473, 5547.861303467284, 5557.305336832896, 5587.636803607753, 5597.524210441995, 5240.606954478506, 5479.424660410759, 5503.752331145028, 5513.09034009384, 5518.91721624184, 5595.860103497445, 5606.215129881902, 5611.74747136388, 5674.233978412862, 5682.801101961411, 5293.947060529499, 5490.984167928946, 5543.861403464945, 5553.333599968029, 5560.916586251128], "value": 4240.596397304572, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.7728442824937, 762.3710438887919, 765.4106338119648, 823.946443481174, 875.2079368171804, 14446.133232006086, 15394.769078463833, 15443.382264709426, 15450.845491545082, 15468.613284667888, 14739.263036848142, 15414.922925385357, 15565.221738913036, 15578.688426231469, 15657.921710391432, 804.175719887116, 822.1408627983772, 823.979400514986, 826.36782861111, 826.4173757500978, 648.4358608011014, 668.6990854115643, 797.2665148063795, 819.2790344496834, 824.6247957179469, 871.4901782457271, 874.2568816505951, 874.4971641306242, 874.7069731639908, 874.8993865705462, 842.9030661473947, 860.2086080805674, 863.0592654007131, 863.2575984653193, 863.2575984653193, 500.93237412949514, 508.47118996243864, 606.9833029675276, 620.0760866309212, 625.4746013348763, 14666.266686665624, 14676.0, 14698.853011469962, 14705.632359190999, 14730.0, 14157.433702651882, 14439.783403248959, 14829.18439485828, 14870.925645371812, 15235.0, 13242.933785331012, 13263.204207747565, 13372.398242079129, 13637.318134093355, 13788.586342409644, 3984.721069525134, 3988.202359528106, 3991.7968574579227, 4038.5557588665224, 4162.771047592381, 83868.0, 84081.57959210227, 85772.0, 85916.0, 88022.0, 11443.141764367705, 11480.540778368771, 11490.94254528722, 11696.064314854792, 12191.87808121925, 22054.88972555127, 22079.0, 22085.558288834134, 22086.558268834855, 22213.777862221494, 7954.244346787046, 8073.425681991956, 8195.7951051223, 8292.917070829215, 8810.911890881138, 4546.499885012646, 4595.862124136282, 4641.164590373765, 4704.835330763407, 4719.339292499053, 3134.905952821419, 3212.100611828692, 3215.565898603705, 3217.533457648628, 3221.5328777327154, 554.0636324611394, 561.1890817768285, 576.345847463124, 586.2466730251606, 586.4223739616474, 542.785599688121, 624.6501958903029, 635.5106567942659, 673.5921923180576, 706.9929300706928, 9416.152546270869, 9759.0, 9781.755456113655, 10144.492775361203, 10182.745431364276, 623.8689875126183, 634.5462993959248, 641.0960545630638, 652.1489456259627, 684.4763755726873, 4263.658907287471, 4792.928106078341, 4850.1269771441475, 4937.241817604027, 4958.0, 120840.39579802044, 122148.38925805314, 124418.0, 126939.0, 128462.0, 104957.0, 105016.0, 106120.0, 107811.46094269636, 108988.4550577242, 92070.99999999866, 92435.53782231046, 94109.5294523523, 96157.5192124035, 98210.5089474548, 8843.69047083349, 8951.73144805657, 8954.865677014981, 8995.685151019683, 9005.279577633904, 54618.45381546134, 54683.179752304546, 54940.17589736077, 55394.0, 55789.72105139448, 76396.94412223449, 77853.61073194824, 81171.59414202892, 81609.0, 85154.0, 11995.880041199476, 12003.399830008639, 12099.153059285765, 12267.325297108744, 12298.20061695987, 13090.280034597808, 13178.472861085449, 13833.170009799444, 13987.880969522617, 14019.985703073888, 11664.183507154758, 12000.579979701019, 12220.755584888075, 12275.631731048083, 12331.753364932472, 11822.645320640395, 12200.14598978063, 12228.266304021787, 12245.57140500114, 12315.44580493862, 12365.381730913597, 12387.75224495523, 12413.131080824256, 12453.688657783265, 12738.363081846055, 8392.244697977212, 8768.956155219183, 10192.592296308065, 10432.791344173225, 10520.947395262974, 9044.50255235942, 9462.952685236529, 9652.517374131407, 9697.903020969983, 9721.9027809721], "value": 7444.840341646973, "unit": "score"}], "name": "dromaeo_css", "value": 7189.33127303995, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 113.0, 111.0, 112.0, 113.0, 113.0, 111.0, 111.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 122.0, 118.0, 165.0, 124.0, 120.0, 118.0, 125.0, 157.0, 125.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 160.0, 159.0, 160.0, 161.0, 161.0, 161.0, 161.0], "value": 160.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 107.0, 78.0, 77.0, 76.0, 75.0, 109.0, 74.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 112.0, 82.0, 82.0, 82.0, 105.0, 82.0, 81.0, 124.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 145.0, 146.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 48.0, 50.0, 51.0, 52.0, 51.0, 51.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 62.0, 61.0, 61.0, 61.0, 62.0, 60.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 79.0, 79.0, 79.0, 79.0, 78.0, 80.0, 78.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 138.0, 124.0, 128.0, 124.0, 122.0, 123.0, 124.0, 164.0, 124.0], "value": 129.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 164.0, 162.0, 165.0, 165.0, 166.0, 167.0, 164.0, 167.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 64.0, 64.0, 64.0, 69.0, 64.0, 62.0, 64.0], "value": 64.7, "unit": "score"}], "name": "kraken", "value": 1484.7, "alertThreshold": 2.0}]} 10:59:30 INFO - Return code: 0 10:59:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:59:30 INFO - # TBPL SUCCESS # 10:59:30 INFO - Running post-action listener: _resource_record_post_action 10:59:30 INFO - [mozharness: 2016-09-19 17:59:30.574264Z] Finished run-tests step (success) 10:59:30 INFO - Running post-run listener: _resource_record_post_run 10:59:30 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 86016; Write bytes: 395333632; Read time: 40; Write time: 324104 10:59:30 INFO - TinderboxPrint: CPU usage
13.1% 10:59:30 INFO - TinderboxPrint: I/O read bytes / time
86,016 / 40 10:59:30 INFO - TinderboxPrint: I/O write bytes / time
395,333,632 / 324,104 10:59:30 INFO - TinderboxPrint: CPU idle
8,288.2 (86.9%) 10:59:30 INFO - TinderboxPrint: CPU user
1,196.0 (12.5%) 10:59:30 INFO - TinderboxPrint: Swap in / out
0 / 0 10:59:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103714816; Read time: 0; Write time: 105372 10:59:30 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 77824; Write bytes: 288845824; Read time: 20; Write time: 218416 10:59:31 INFO - Running post-run listener: _upload_blobber_files 10:59:31 INFO - Blob upload gear active. 10:59:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:59:31 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 10:59:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:59:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:59:31 INFO - (blobuploader) - INFO - Open directory for files ... 10:59:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:59:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:59:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:32 INFO - (blobuploader) - INFO - Done attempting. 10:59:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:59:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:59:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:37 INFO - (blobuploader) - INFO - Done attempting. 10:59:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:59:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:59:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:59:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:59:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:59:38 INFO - (blobuploader) - INFO - Done attempting. 10:59:38 INFO - (blobuploader) - INFO - Iteration through files over. 10:59:38 INFO - Return code: 0 10:59:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:59:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:59:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fedcceef97d8d9da7bf0fef62d83e48886571e272ee8cac39dbdea964acae8a8332416ccfe855d269ca68c90b124003745f7b712c0fa1bf0058b3e626c04c354", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/880cf42b4fe6fa45b43d4994336024ea9a93d2c76d45aa5baf99c747d957b2d8592eb312747d25acba0bef76ba77f86743e9d11f5abbb9f16dc4738b2b2c810d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2aad141ea9b8eb03497f5647a5c392fdd2100b5be9f877321d7cc13643f18658757186a06b0484d1394d7ed2259bd215552a2f22e8afdb2f89be2bad34626054"} 10:59:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:59:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:59:38 INFO - Contents: 10:59:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fedcceef97d8d9da7bf0fef62d83e48886571e272ee8cac39dbdea964acae8a8332416ccfe855d269ca68c90b124003745f7b712c0fa1bf0058b3e626c04c354", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/880cf42b4fe6fa45b43d4994336024ea9a93d2c76d45aa5baf99c747d957b2d8592eb312747d25acba0bef76ba77f86743e9d11f5abbb9f16dc4738b2b2c810d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2aad141ea9b8eb03497f5647a5c392fdd2100b5be9f877321d7cc13643f18658757186a06b0484d1394d7ed2259bd215552a2f22e8afdb2f89be2bad34626054"} 10:59:38 INFO - Running post-run listener: copy_logs_to_upload_dir 10:59:38 INFO - Copying logs to upload dir... 10:59:38 INFO - mkdir: /builds/slave/test/build/upload/logs 10:59:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.910301 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-09-19 10:59:38.247326) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 10:59:38.252154) ========= 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=826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fedcceef97d8d9da7bf0fef62d83e48886571e272ee8cac39dbdea964acae8a8332416ccfe855d269ca68c90b124003745f7b712c0fa1bf0058b3e626c04c354", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/880cf42b4fe6fa45b43d4994336024ea9a93d2c76d45aa5baf99c747d957b2d8592eb312747d25acba0bef76ba77f86743e9d11f5abbb9f16dc4738b2b2c810d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2aad141ea9b8eb03497f5647a5c392fdd2100b5be9f877321d7cc13643f18658757186a06b0484d1394d7ed2259bd215552a2f22e8afdb2f89be2bad34626054"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-linux64-pgo/1474291800/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fedcceef97d8d9da7bf0fef62d83e48886571e272ee8cac39dbdea964acae8a8332416ccfe855d269ca68c90b124003745f7b712c0fa1bf0058b3e626c04c354", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/880cf42b4fe6fa45b43d4994336024ea9a93d2c76d45aa5baf99c747d957b2d8592eb312747d25acba0bef76ba77f86743e9d11f5abbb9f16dc4738b2b2c810d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2aad141ea9b8eb03497f5647a5c392fdd2100b5be9f877321d7cc13643f18658757186a06b0484d1394d7ed2259bd215552a2f22e8afdb2f89be2bad34626054"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 10:59:38.277408) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:59:38.277757) ========= 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=826874ccc97bd7bf541b4875000001d5-1474306740.850305-1674307173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004504 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 10:59:38.363219) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 10:59:38.364091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 10:59:38.364791) ========= ========= Total master_lag: 0.18 =========