builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1474125060.22 results: success (0) buildid: 20160917075418 builduid: 2d56e7d07d434b348d1f14c5965643fc revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:00.217314) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:00.217778) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:00.218049) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:00.235482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:00.235868) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-17 08:11:00-- 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% 33.2M=0s 2016-09-17 08:11:00 (33.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109637 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:00.359156) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:00.359462) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020110 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:00.408494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 08:11:00.408849) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev b401cb17167b34c362eb819259effbb3c0979f59 --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=56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-17 08:11:00,577 truncating revision to first 12 chars 2016-09-17 08:11:00,577 Setting DEBUG logging. 2016-09-17 08:11:00,577 attempt 1/10 2016-09-17 08:11:00,577 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b401cb17167b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-17 08:11:01,345 unpacking tar archive at: mozilla-central-b401cb17167b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.116213 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-17 08:11:01.538274) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:01.538588) ========= script_repo_revision: b401cb17167b34c362eb819259effbb3c0979f59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:01.538983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:01.539242) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-17 08:11:01.548642) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-17 08:11:01.548927) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:11:01 INFO - MultiFileLogger online at 20160917 08:11:01 in /builds/slave/test 08:11:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:11:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:11:01 INFO - {'append_to_log': False, 08:11:01 INFO - 'base_work_dir': '/builds/slave/test', 08:11:01 INFO - 'blob_upload_branch': 'Firefox', 08:11:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:11:01 INFO - 'branch': 'Firefox', 08:11:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:11:01 INFO - 'config_files': ('talos/linux_config.py',), 08:11:01 INFO - 'default_actions': ('clobber', 08:11:01 INFO - 'read-buildbot-config', 08:11:01 INFO - 'download-and-extract', 08:11:01 INFO - 'populate-webroot', 08:11:01 INFO - 'create-virtualenv', 08:11:01 INFO - 'install', 08:11:01 INFO - 'run-tests'), 08:11:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:11:01 INFO - 'download_minidump_stackwalk': True, 08:11:01 INFO - 'download_symbols': 'ondemand', 08:11:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:11:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:11:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:11:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:11:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:11:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:11:01 INFO - 'installer_path': 'installer.exe', 08:11:01 INFO - 'log_level': 'info', 08:11:01 INFO - 'log_name': 'talos', 08:11:01 INFO - 'log_to_console': True, 08:11:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:11:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:11:01 INFO - 'opt_config_files': (), 08:11:01 INFO - 'pip_index': False, 08:11:01 INFO - 'sps_profile': False, 08:11:01 INFO - 'sps_profile_interval': 0, 08:11:01 INFO - 'suite': 'dromaeojs', 08:11:01 INFO - 'system_bits': '32', 08:11:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:11:01 INFO - 'title': 'talos-linux64-ix-037', 08:11:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:11:01 INFO - 'use_talos_json': True, 08:11:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:11:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:11:01 INFO - 'work_dir': 'build'} 08:11:01 INFO - [mozharness: 2016-09-17 15:11:01.815683Z] Running clobber step. 08:11:01 INFO - Running pre-action listener: _resource_record_pre_action 08:11:01 INFO - Running main action method: clobber 08:11:01 INFO - rmtree: /builds/slave/test/build 08:11:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:11:02 INFO - Running post-action listener: _resource_record_post_action 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.270903Z] Finished clobber step (success) 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.271003Z] Running read-buildbot-config step. 08:11:02 INFO - Running pre-action listener: _resource_record_pre_action 08:11:02 INFO - Running main action method: read_buildbot_config 08:11:02 INFO - Using buildbot properties: 08:11:02 INFO - { 08:11:02 INFO - "project": "", 08:11:02 INFO - "product": "firefox", 08:11:02 INFO - "script_repo_revision": "production", 08:11:02 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:11:02 INFO - "repository": "", 08:11:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:11:02 INFO - "buildid": "20160917075418", 08:11:02 INFO - "stage_platform": "linux64-pgo", 08:11:02 INFO - "basedir": "/builds/slave/test", 08:11:02 INFO - "buildnumber": 577, 08:11:02 INFO - "platform": "ubuntu64_hw", 08:11:02 INFO - "mozci_request": { 08:11:02 INFO - "priority": -1, 08:11:02 INFO - "type": "trigger_all_talos_jobs", 08:11:02 INFO - "times": 6 08:11:02 INFO - }, 08:11:02 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:11:02 INFO - "slavebuilddir": "test", 08:11:02 INFO - "branch": "mozilla-central", 08:11:02 INFO - "revision": "b401cb17167b34c362eb819259effbb3c0979f59", 08:11:02 INFO - "slavename": "talos-linux64-ix-037", 08:11:02 INFO - "builduid": "2d56e7d07d434b348d1f14c5965643fc", 08:11:02 INFO - "repo_path": "mozilla-central" 08:11:02 INFO - } 08:11:02 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:11:02 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 08:11:02 INFO - Running post-action listener: _resource_record_post_action 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.273770Z] Finished read-buildbot-config step (success) 08:11:02 INFO - [mozharness: 2016-09-17 15:11:02.273859Z] Running download-and-extract step. 08:11:02 INFO - Running pre-action listener: _resource_record_pre_action 08:11:02 INFO - Running main action method: download_and_extract 08:11:02 INFO - mkdir: /builds/slave/test/build/tests 08:11: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')]} 08:11:02 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:11:02 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/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 08:11:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/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 08:11:02 INFO - Downloaded 1361 bytes. 08:11:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:11:02 INFO - Using the following test package requirements: 08:11:02 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:11:02 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:11:02 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:11:02 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'jsshell-linux-x86_64.zip'], 08:11:02 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:11:02 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:11:02 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:11:02 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:11:02 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:11:02 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:11:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:11:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:11:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:11:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:11:02 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:11:02 INFO - Expected file size: 21936347 08:11:06 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 08:11:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:11:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:11:06 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:11:06 INFO - Expected file size: 21936347 08:11:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:11:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:11:11 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:11:11 INFO - Expected file size: 14414048 08:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:11 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:11 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/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 08:11:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/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 08:11:12 INFO - Downloaded 59535581 bytes. 08:11:12 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:12 INFO - mkdir: /builds/slave/test/properties 08:11:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:11:12 INFO - Writing to file /builds/slave/test/properties/build_url 08:11:12 INFO - Contents: 08:11:12 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:11:12 INFO - Running post-action listener: _resource_record_post_action 08:11:12 INFO - Running post-action listener: set_extra_try_arguments 08:11:12 INFO - [mozharness: 2016-09-17 15:11:12.836602Z] Finished download-and-extract step (success) 08:11:12 INFO - [mozharness: 2016-09-17 15:11:12.836832Z] Running populate-webroot step. 08:11:12 INFO - Running pre-action listener: _resource_record_pre_action 08:11:12 INFO - Running main action method: populate_webroot 08:11:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:11:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:11:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:11:12 INFO - '--tppagecycles', 08:11:12 INFO - '7'], 08:11:12 INFO - 'tests': ['tsvgm']}}, 08:11:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:11:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:11:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:11:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:11:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['damp', 'tps']}, 08:11:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'tests': ['damp', 'tps']}, 08:11:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['dromaeo_dom']}, 08:11:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:11:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['basic_compositor_video']}, 08:11:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:11:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other-e10s': {'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['a11yr', 08:11:12 INFO - 'ts_paint', 08:11:12 INFO - 'tpaint', 08:11:12 INFO - 'sessionrestore', 08:11:12 INFO - 'sessionrestore_no_auto_restore', 08:11:12 INFO - 'tabpaint']}, 08:11:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['tsvgx', 08:11:12 INFO - 'tsvgr_opacity', 08:11:12 INFO - 'tart', 08:11:12 INFO - 'tscrollx', 08:11:12 INFO - 'cart']}, 08:11:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:11:12 INFO - 'tsvgr_opacity', 08:11:12 INFO - 'tart', 08:11:12 INFO - 'tscrollx', 08:11:12 INFO - 'cart']}, 08:11:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--disable-e10s'], 08:11:12 INFO - 'tests': ['tp5o']}, 08:11:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'tests': ['tp5o']}, 08:11:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--disable-e10s', 08:11:12 INFO - '--xperf_path', 08:11:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:12 INFO - 'tests': ['tp5n']}, 08:11:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:12 INFO - 'talos_options': ['--xperf_path', 08:11:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:12 INFO - 'tests': ['tp5n']}}, 08:11:12 INFO - 'talos.zip': {'path': '', 08:11:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:11:12 INFO - Running post-action listener: _resource_record_post_action 08:11:12 INFO - [mozharness: 2016-09-17 15:11:12.854061Z] Finished populate-webroot step (success) 08:11:12 INFO - [mozharness: 2016-09-17 15:11:12.854150Z] Running create-virtualenv step. 08:11:12 INFO - Running pre-action listener: _resource_record_pre_action 08:11:12 INFO - Running main action method: create_virtualenv 08:11:12 INFO - Creating virtualenv /builds/slave/test/build/venv 08:11:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:11:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:11:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:11:12 INFO - Using real prefix '/usr' 08:11:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:11:13 INFO - Installing distribute.............................................................................................................................................................................................done. 08:11:15 INFO - Installing pip.................done. 08:11:15 INFO - Return code: 0 08:11:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:11:15 INFO - Using env: {'DISPLAY': ':0', 08:11:15 INFO - 'HOME': '/home/cltbld', 08:11:15 INFO - 'LANG': 'en_US.UTF-8', 08:11:15 INFO - 'LANGUAGE': 'en_US:en', 08:11:15 INFO - 'LOGNAME': 'cltbld', 08:11:15 INFO - 'MAIL': '/var/mail/cltbld', 08:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:15 INFO - 'MOZ_NO_REMOTE': '1', 08:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:15 INFO - 'NO_EM_RESTART': '1', 08:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:15 INFO - 'PWD': '/builds/slave/test', 08:11:15 INFO - 'SHELL': '/bin/bash', 08:11:15 INFO - 'SHLVL': '1', 08:11:15 INFO - 'TERM': 'linux', 08:11:15 INFO - 'TMOUT': '86400', 08:11:15 INFO - 'USER': 'cltbld', 08:11:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:15 INFO - '_': '/tools/buildbot/bin/python'} 08:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:11:15 INFO - Cleaning up... 08:11:15 INFO - Return code: 0 08:11:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:11:15 INFO - Using env: {'DISPLAY': ':0', 08:11:15 INFO - 'HOME': '/home/cltbld', 08:11:15 INFO - 'LANG': 'en_US.UTF-8', 08:11:15 INFO - 'LANGUAGE': 'en_US:en', 08:11:15 INFO - 'LOGNAME': 'cltbld', 08:11:15 INFO - 'MAIL': '/var/mail/cltbld', 08:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:15 INFO - 'MOZ_NO_REMOTE': '1', 08:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:15 INFO - 'NO_EM_RESTART': '1', 08:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:15 INFO - 'PWD': '/builds/slave/test', 08:11:15 INFO - 'SHELL': '/bin/bash', 08:11:15 INFO - 'SHLVL': '1', 08:11:15 INFO - 'TERM': 'linux', 08:11:15 INFO - 'TMOUT': '86400', 08:11:15 INFO - 'USER': 'cltbld', 08:11:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:15 INFO - '_': '/tools/buildbot/bin/python'} 08:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:15 INFO - Downloading/unpacking psutil>=3.1.1 08:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:11:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:17 INFO - Installing collected packages: psutil 08:11:17 INFO - Running setup.py install for psutil 08:11:17 INFO - building 'psutil._psutil_linux' extension 08:11:17 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:11: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 08:11:18 INFO - building 'psutil._psutil_posix' extension 08:11: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 08:11: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 08:11:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:18 INFO - Successfully installed psutil 08:11:18 INFO - Cleaning up... 08:11:18 INFO - Return code: 0 08:11:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:18 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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:11:18 INFO - Using env: {'DISPLAY': ':0', 08:11:18 INFO - 'HOME': '/home/cltbld', 08:11:18 INFO - 'LANG': 'en_US.UTF-8', 08:11:18 INFO - 'LANGUAGE': 'en_US:en', 08:11:18 INFO - 'LOGNAME': 'cltbld', 08:11:18 INFO - 'MAIL': '/var/mail/cltbld', 08:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:18 INFO - 'MOZ_NO_REMOTE': '1', 08:11:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:18 INFO - 'NO_EM_RESTART': '1', 08:11:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:18 INFO - 'PWD': '/builds/slave/test', 08:11:18 INFO - 'SHELL': '/bin/bash', 08:11:18 INFO - 'SHLVL': '1', 08:11:18 INFO - 'TERM': 'linux', 08:11:18 INFO - 'TMOUT': '86400', 08:11:18 INFO - 'USER': 'cltbld', 08:11:18 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:18 INFO - '_': '/tools/buildbot/bin/python'} 08:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:11:20 INFO - Installing collected packages: mozsystemmonitor 08:11:20 INFO - Running setup.py install for mozsystemmonitor 08:11:20 INFO - Successfully installed mozsystemmonitor 08:11:20 INFO - Cleaning up... 08:11:20 INFO - Return code: 0 08:11:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:11:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:11:20 INFO - Using env: {'DISPLAY': ':0', 08:11:20 INFO - 'HOME': '/home/cltbld', 08:11:20 INFO - 'LANG': 'en_US.UTF-8', 08:11:20 INFO - 'LANGUAGE': 'en_US:en', 08:11:20 INFO - 'LOGNAME': 'cltbld', 08:11:20 INFO - 'MAIL': '/var/mail/cltbld', 08:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:20 INFO - 'MOZ_NO_REMOTE': '1', 08:11:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:20 INFO - 'NO_EM_RESTART': '1', 08:11:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:20 INFO - 'PWD': '/builds/slave/test', 08:11:20 INFO - 'SHELL': '/bin/bash', 08:11:20 INFO - 'SHLVL': '1', 08:11:20 INFO - 'TERM': 'linux', 08:11:20 INFO - 'TMOUT': '86400', 08:11:20 INFO - 'USER': 'cltbld', 08:11:20 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:20 INFO - '_': '/tools/buildbot/bin/python'} 08:11:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:20 INFO - Downloading/unpacking jsonschema==2.5.1 08:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:11:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:23 INFO - Downloading functools32-3.2.3-2.tar.gz 08:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:11:23 INFO - warning: no files found matching '*.txt' 08:11:23 INFO - no previously-included directories found matching 'build' 08:11:23 INFO - no previously-included directories found matching 'dist' 08:11:23 INFO - no previously-included directories found matching '.git*' 08:11:23 INFO - Installing collected packages: jsonschema, functools32 08:11:23 INFO - Running setup.py install for functools32 08:11:23 INFO - warning: no files found matching '*.txt' 08:11:23 INFO - no previously-included directories found matching 'build' 08:11:23 INFO - no previously-included directories found matching 'dist' 08:11:23 INFO - no previously-included directories found matching '.git*' 08:11:23 INFO - Successfully installed jsonschema functools32 08:11:23 INFO - Cleaning up... 08:11:23 INFO - Return code: 0 08:11:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11: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 08:11: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 08:11:23 INFO - Using env: {'DISPLAY': ':0', 08:11:23 INFO - 'HOME': '/home/cltbld', 08:11:23 INFO - 'LANG': 'en_US.UTF-8', 08:11:23 INFO - 'LANGUAGE': 'en_US:en', 08:11:23 INFO - 'LOGNAME': 'cltbld', 08:11:23 INFO - 'MAIL': '/var/mail/cltbld', 08:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:23 INFO - 'MOZ_NO_REMOTE': '1', 08:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:23 INFO - 'NO_EM_RESTART': '1', 08:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:23 INFO - 'PWD': '/builds/slave/test', 08:11:23 INFO - 'SHELL': '/bin/bash', 08:11:23 INFO - 'SHLVL': '1', 08:11:23 INFO - 'TERM': 'linux', 08:11:23 INFO - 'TMOUT': '86400', 08:11:23 INFO - 'USER': 'cltbld', 08:11:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:23 INFO - '_': '/tools/buildbot/bin/python'} 08:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:11:23 INFO - Cleaning up... 08:11:23 INFO - Return code: 0 08:11:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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', '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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:11: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 blobuploader==1.2.4 08:11:23 INFO - Using env: {'DISPLAY': ':0', 08:11:23 INFO - 'HOME': '/home/cltbld', 08:11:23 INFO - 'LANG': 'en_US.UTF-8', 08:11:23 INFO - 'LANGUAGE': 'en_US:en', 08:11:23 INFO - 'LOGNAME': 'cltbld', 08:11:23 INFO - 'MAIL': '/var/mail/cltbld', 08:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:23 INFO - 'MOZ_NO_REMOTE': '1', 08:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:23 INFO - 'NO_EM_RESTART': '1', 08:11:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:23 INFO - 'PWD': '/builds/slave/test', 08:11:23 INFO - 'SHELL': '/bin/bash', 08:11:23 INFO - 'SHLVL': '1', 08:11:23 INFO - 'TERM': 'linux', 08:11:23 INFO - 'TMOUT': '86400', 08:11:23 INFO - 'USER': 'cltbld', 08:11:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:23 INFO - '_': '/tools/buildbot/bin/python'} 08:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:23 INFO - Downloading/unpacking blobuploader==1.2.4 08:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:25 INFO - Downloading blobuploader-1.2.4.tar.gz 08:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:11:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:11:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:26 INFO - Downloading docopt-0.6.1.tar.gz 08:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:11:26 INFO - Installing collected packages: blobuploader, requests, docopt 08:11:26 INFO - Running setup.py install for blobuploader 08:11:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:11:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:11:26 INFO - Running setup.py install for requests 08:11:26 INFO - Running setup.py install for docopt 08:11:26 INFO - Successfully installed blobuploader requests docopt 08:11:26 INFO - Cleaning up... 08:11:26 INFO - Return code: 0 08:11:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:26 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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:26 INFO - Using env: {'DISPLAY': ':0', 08:11:26 INFO - 'HOME': '/home/cltbld', 08:11:26 INFO - 'LANG': 'en_US.UTF-8', 08:11:26 INFO - 'LANGUAGE': 'en_US:en', 08:11:26 INFO - 'LOGNAME': 'cltbld', 08:11:26 INFO - 'MAIL': '/var/mail/cltbld', 08:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:26 INFO - 'MOZ_NO_REMOTE': '1', 08:11:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:26 INFO - 'NO_EM_RESTART': '1', 08:11:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:26 INFO - 'PWD': '/builds/slave/test', 08:11:26 INFO - 'SHELL': '/bin/bash', 08:11:26 INFO - 'SHLVL': '1', 08:11:26 INFO - 'TERM': 'linux', 08:11:26 INFO - 'TMOUT': '86400', 08:11:26 INFO - 'USER': 'cltbld', 08:11:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:26 INFO - '_': '/tools/buildbot/bin/python'} 08:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:27 INFO - Running setup.py (path:/tmp/pip-EEBz99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:27 INFO - Running setup.py (path:/tmp/pip-ouZZut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:27 INFO - Running setup.py (path:/tmp/pip-VdvVjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:27 INFO - Running setup.py (path:/tmp/pip-_uzpR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:27 INFO - Running setup.py (path:/tmp/pip-0zNEdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:27 INFO - Running setup.py (path:/tmp/pip-nZmkCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:27 INFO - Running setup.py (path:/tmp/pip-arjRtB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:27 INFO - Running setup.py (path:/tmp/pip-bJQjF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:27 INFO - Running setup.py (path:/tmp/pip-_YRMTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:27 INFO - Running setup.py (path:/tmp/pip-aRz1gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:27 INFO - Running setup.py (path:/tmp/pip-WSLfpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:27 INFO - Running setup.py (path:/tmp/pip-5rcyuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:28 INFO - Running setup.py (path:/tmp/pip-s3umbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:28 INFO - Running setup.py (path:/tmp/pip-1kblw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:28 INFO - Running setup.py (path:/tmp/pip-YkE6Uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:28 INFO - Running setup.py (path:/tmp/pip-qv9SP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:28 INFO - Running setup.py (path:/tmp/pip-5RsDU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:11:28 INFO - Running setup.py install for manifestparser 08:11:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Running setup.py install for mozcrash 08:11:28 INFO - Running setup.py install for mozdebug 08:11:28 INFO - Running setup.py install for mozdevice 08:11:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:11:28 INFO - Running setup.py install for mozfile 08:11:28 INFO - Running setup.py install for mozhttpd 08:11:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozinfo 08:11:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozInstall 08:11:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozleak 08:11:29 INFO - Running setup.py install for mozlog 08:11:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for moznetwork 08:11:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozprocess 08:11:29 INFO - Running setup.py install for mozprofile 08:11:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozrunner 08:11:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:11:29 INFO - Running setup.py install for mozscreenshot 08:11:29 INFO - Running setup.py install for moztest 08:11:30 INFO - Running setup.py install for mozversion 08:11:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:11:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:11:30 INFO - Cleaning up... 08:11:30 INFO - Return code: 0 08:11:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11: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 08:11: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 08:11:30 INFO - Using env: {'DISPLAY': ':0', 08:11:30 INFO - 'HOME': '/home/cltbld', 08:11:30 INFO - 'LANG': 'en_US.UTF-8', 08:11:30 INFO - 'LANGUAGE': 'en_US:en', 08:11:30 INFO - 'LOGNAME': 'cltbld', 08:11:30 INFO - 'MAIL': '/var/mail/cltbld', 08:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:30 INFO - 'MOZ_NO_REMOTE': '1', 08:11:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:30 INFO - 'NO_EM_RESTART': '1', 08:11:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:30 INFO - 'PWD': '/builds/slave/test', 08:11:30 INFO - 'SHELL': '/bin/bash', 08:11:30 INFO - 'SHLVL': '1', 08:11:30 INFO - 'TERM': 'linux', 08:11:30 INFO - 'TMOUT': '86400', 08:11:30 INFO - 'USER': 'cltbld', 08:11:30 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:30 INFO - '_': '/tools/buildbot/bin/python'} 08:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:30 INFO - Running setup.py (path:/tmp/pip-0JdS8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:30 INFO - Running setup.py (path:/tmp/pip-OcQjsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:30 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)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:30 INFO - Running setup.py (path:/tmp/pip-kKGZik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:30 INFO - Running setup.py (path:/tmp/pip-3CMX6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:30 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)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:30 INFO - Running setup.py (path:/tmp/pip-4lUKQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:30 INFO - Running setup.py (path:/tmp/pip-b3wN6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:30 INFO - Running setup.py (path:/tmp/pip-YTNAlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:30 INFO - Running setup.py (path:/tmp/pip-tCsxqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11: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)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:31 INFO - Running setup.py (path:/tmp/pip-ec3pqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11: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)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:31 INFO - Running setup.py (path:/tmp/pip-2cytQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11: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)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:31 INFO - Running setup.py (path:/tmp/pip-hOMNOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11: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)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:31 INFO - Running setup.py (path:/tmp/pip-LX7ESs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11: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)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:31 INFO - Running setup.py (path:/tmp/pip-sfr_Hg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:31 INFO - Running setup.py (path:/tmp/pip-s0XSBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:31 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)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:31 INFO - Running setup.py (path:/tmp/pip-65ACT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:31 INFO - Running setup.py (path:/tmp/pip-1LmEOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:31 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)) 08:11:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:31 INFO - Running setup.py (path:/tmp/pip-lSChTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:11:31 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)) 08:11:31 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)) 08:11:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:11:31 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)) 08:11:31 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)) 08:11:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:33 INFO - Downloading blessings-1.6.tar.gz 08:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:11:33 INFO - Installing collected packages: blessings 08:11:33 INFO - Running setup.py install for blessings 08:11:33 INFO - Successfully installed blessings 08:11:33 INFO - Cleaning up... 08:11:33 INFO - Return code: 0 08:11:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:11:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:34 INFO - Reading from file tmpfile_stdout 08:11:34 INFO - Current package versions: 08:11:34 INFO - argparse == 1.2.1 08:11:34 INFO - blessings == 1.6 08:11:34 INFO - blobuploader == 1.2.4 08:11:34 INFO - docopt == 0.6.1 08:11:34 INFO - functools32 == 3.2.3-2 08:11:34 INFO - jsonschema == 2.5.1 08:11:34 INFO - manifestparser == 1.1 08:11:34 INFO - mozInstall == 1.12 08:11:34 INFO - mozcrash == 0.17 08:11:34 INFO - mozdebug == 0.1 08:11:34 INFO - mozdevice == 0.48 08:11:34 INFO - mozfile == 1.2 08:11:34 INFO - mozhttpd == 0.7 08:11:34 INFO - mozinfo == 0.9 08:11:34 INFO - mozleak == 0.1 08:11:34 INFO - mozlog == 3.4 08:11:34 INFO - moznetwork == 0.27 08:11:34 INFO - mozprocess == 0.23 08:11:34 INFO - mozprofile == 0.28 08:11:34 INFO - mozrunner == 6.12 08:11:34 INFO - mozscreenshot == 0.1 08:11:34 INFO - mozsystemmonitor == 0.3 08:11:34 INFO - moztest == 0.7 08:11:34 INFO - mozversion == 1.4 08:11:34 INFO - psutil == 3.1.1 08:11:34 INFO - requests == 1.2.3 08:11:34 INFO - wsgiref == 0.1.2 08:11:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11: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 08:11: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 08:11:34 INFO - Using env: {'DISPLAY': ':0', 08:11:34 INFO - 'HOME': '/home/cltbld', 08:11:34 INFO - 'LANG': 'en_US.UTF-8', 08:11:34 INFO - 'LANGUAGE': 'en_US:en', 08:11:34 INFO - 'LOGNAME': 'cltbld', 08:11:34 INFO - 'MAIL': '/var/mail/cltbld', 08:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:34 INFO - 'MOZ_NO_REMOTE': '1', 08:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:34 INFO - 'NO_EM_RESTART': '1', 08:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:34 INFO - 'PWD': '/builds/slave/test', 08:11:34 INFO - 'SHELL': '/bin/bash', 08:11:34 INFO - 'SHLVL': '1', 08:11:34 INFO - 'TERM': 'linux', 08:11:34 INFO - 'TMOUT': '86400', 08:11:34 INFO - 'USER': 'cltbld', 08:11:34 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:34 INFO - '_': '/tools/buildbot/bin/python'} 08:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11: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)) 08:11: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)) 08:11: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)) 08:11: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)) 08:11: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)) 08:11: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)) 08:11: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)) 08:11: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)) 08:11: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)) 08:11: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)) 08:11: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)) 08:11:34 INFO - Cleaning up... 08:11:34 INFO - Return code: 0 08:11:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:11: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')]} 08:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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 0x7f9787134f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11397b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11: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 08:11: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 08:11:34 INFO - Using env: {'DISPLAY': ':0', 08:11:34 INFO - 'HOME': '/home/cltbld', 08:11:34 INFO - 'LANG': 'en_US.UTF-8', 08:11:34 INFO - 'LANGUAGE': 'en_US:en', 08:11:34 INFO - 'LOGNAME': 'cltbld', 08:11:34 INFO - 'MAIL': '/var/mail/cltbld', 08:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:34 INFO - 'MOZ_NO_REMOTE': '1', 08:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:34 INFO - 'NO_EM_RESTART': '1', 08:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:34 INFO - 'PWD': '/builds/slave/test', 08:11:34 INFO - 'SHELL': '/bin/bash', 08:11:34 INFO - 'SHLVL': '1', 08:11:34 INFO - 'TERM': 'linux', 08:11:34 INFO - 'TMOUT': '86400', 08:11:34 INFO - 'USER': 'cltbld', 08:11:34 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:34 INFO - '_': '/tools/buildbot/bin/python'} 08:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:11:34 INFO - Cleaning up... 08:11:34 INFO - Return code: 0 08:11:34 INFO - Running post-action listener: _resource_record_post_action 08:11:34 INFO - Running post-action listener: _start_resource_monitoring 08:11:34 INFO - Starting resource monitoring. 08:11:34 INFO - [mozharness: 2016-09-17 15:11:34.515751Z] Finished create-virtualenv step (success) 08:11:34 INFO - [mozharness: 2016-09-17 15:11:34.516262Z] Running install step. 08:11:34 INFO - Running pre-action listener: _resource_record_pre_action 08:11:34 INFO - Running main action method: install 08:11:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:34 INFO - Reading from file tmpfile_stdout 08:11:34 INFO - Detecting whether we're running mozinstall >=1.0... 08:11:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:11:34 INFO - Reading from file tmpfile_stdout 08:11:34 INFO - Output received: 08:11:34 INFO - Usage: mozinstall [options] installer 08:11:34 INFO - Options: 08:11:34 INFO - -h, --help show this help message and exit 08:11:34 INFO - -d DEST, --destination=DEST 08:11:34 INFO - Directory to install application into. [default: 08:11:34 INFO - "/builds/slave/test"] 08:11:34 INFO - --app=APP Application being installed. [default: firefox] 08:11:34 INFO - mkdir: /builds/slave/test/build/application 08:11:34 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'] 08:11:34 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 08:11:51 INFO - Reading from file tmpfile_stdout 08:11:51 INFO - Output received: 08:11:51 INFO - /builds/slave/test/build/application/firefox/firefox 08:11:51 INFO - Running post-action listener: _resource_record_post_action 08:11:51 INFO - [mozharness: 2016-09-17 15:11:51.768793Z] Finished install step (success) 08:11:51 INFO - [mozharness: 2016-09-17 15:11:51.769039Z] Running run-tests step. 08:11:51 INFO - Running pre-action listener: _resource_record_pre_action 08:11:51 INFO - Running main action method: run_tests 08:11:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:11:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:11:51 INFO - Python 2.7.3 08:11:51 INFO - Return code: 0 08:11:51 INFO - grabbing minidump binary from tooltool 08:11:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:51 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 0x11c8790>, '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 0x11c9690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11c9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:11:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:11:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:11:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:11:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:51 INFO - Return code: 0 08:11:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:11:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:11:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:11:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:11:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:11:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:11:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:11:51 INFO - Using env: {'DISPLAY': ':0', 08:11:51 INFO - 'HOME': '/home/cltbld', 08:11:51 INFO - 'LANG': 'en_US.UTF-8', 08:11:51 INFO - 'LANGUAGE': 'en_US:en', 08:11:51 INFO - 'LOGNAME': 'cltbld', 08:11:51 INFO - 'MAIL': '/var/mail/cltbld', 08:11:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:11:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:51 INFO - 'MOZ_NO_REMOTE': '1', 08:11:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:11:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:51 INFO - 'NO_EM_RESTART': '1', 08:11:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:51 INFO - 'PWD': '/builds/slave/test', 08:11:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:11:51 INFO - 'SHELL': '/bin/bash', 08:11:51 INFO - 'SHLVL': '1', 08:11:51 INFO - 'TERM': 'linux', 08:11:51 INFO - 'TMOUT': '86400', 08:11:51 INFO - 'USER': 'cltbld', 08:11:51 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624', 08:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:51 INFO - '_': '/tools/buildbot/bin/python'} 08:11:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:11:52 INFO - mozversion application_buildid: 20160916163001 08:11:52 INFO - mozversion application_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:11:52 INFO - mozversion application_display_name: Nightly 08:11:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:52 INFO - mozversion application_name: Firefox 08:11:52 INFO - mozversion application_remotingname: firefox 08:11:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:11:52 INFO - mozversion application_vendor: Mozilla 08:11:52 INFO - mozversion application_version: 51.0a1 08:11:52 INFO - mozversion platform_buildid: 20160916163001 08:11:52 INFO - mozversion platform_changeset: b401cb17167b34c362eb819259effbb3c0979f59 08:11:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:11:52 INFO - mozversion platform_version: 51.0a1 08:11:52 INFO - using testdate: 1474125112 08:11:52 INFO - actual date: 1474125112 08:11:52 INFO - starting webserver on 'localhost:33267' 08:11:52 INFO - SUITE-START | Running 2 tests 08:11:52 INFO - TEST-START | dromaeo_css 08:11:52 INFO - operating with platform_type : linux_ 08:11:52 INFO - Initialising browser for dromaeo_css test... 08:11:52 INFO - TEST-INFO | started process 17744 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplgpijw/profile http://localhost:33267/getInfo.html) 08:11:56 INFO - PROCESS | 17744 | __metrics Screen width/height:1600/1200 08:11:56 INFO - PROCESS | 17744 | colorDepth:24 08:11:56 INFO - PROCESS | 17744 | Browser inner width/height: 1024/697 08:11:56 INFO - PROCESS | 17744 | __metrics 08:11:56 INFO - PROCESS | 17744 | 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" 08:11:56 INFO - PROCESS | 17744 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:57 INFO - PROCESS | 17744 | console.error: PushService: 08:11:57 INFO - PROCESS | 17744 | stateChangeProcessEnqueue: Error transitioning state 08:11:57 INFO - PROCESS | 17744 | UnknownError 08:11:57 INFO - TEST-INFO | 17744: exit 0 08:11:57 INFO - Browser initialized. 08:11:57 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:57 INFO - TEST-INFO | started process 17828 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplgpijw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:10 INFO - PROCESS | 17828 | RSS: Main: 172998656 08:12:10 INFO - PROCESS | 17828 | 08:12:27 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:12:29 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:12:44 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 08:12:51 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.03332 seconds. 08:12:53 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:13:01 INFO - PROCESS | 17828 | 1474125181578 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] 08:13:03 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.04999 seconds. 08:13:10 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:13:12 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:13:21 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 08:13:32 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:13:34 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.16669 seconds. 08:13:36 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:13:38 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 08:13:46 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:13:49 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:13:51 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.1667 seconds. 08:13:59 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:14:02 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:14:16 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.03334 seconds. 08:14:23 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:14:32 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:14:34 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 08:14:41 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:14:51 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.05002 seconds. 08:14:55 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:15:09 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 08:15:11 INFO - PROCESS | 17828 | Cycle 1(1): loaded http://localhost:33267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33267/tests/dromaeo/cssquery-ext.html) 08:15:12 INFO - PROCESS | 17828 | RSS: Main: 305328128 08:15:12 INFO - PROCESS | 17828 | 08:15:22 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 08:15:39 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:15:45 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:15:53 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:16:19 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:16:32 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:16:47 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:16:58 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:17:12 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:17:14 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:17:22 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 08:17:28 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:17:30 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 08:17:32 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:17:41 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:17:44 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:17:46 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:17:50 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:18:14 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:18:14 INFO - PROCESS | 17828 | Cycle 1(1): loaded http://localhost:33267/tests/dromaeo/cssquery-ext.html (next: http://localhost:33267/tests/dromaeo/cssquery-jquery.html) 08:18:15 INFO - PROCESS | 17828 | RSS: Main: 287854592 08:18:15 INFO - PROCESS | 17828 | 08:18:20 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.05003 seconds. 08:18:33 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:18:41 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:18:48 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:18:52 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:19:06 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:19:32 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:19:40 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:19:59 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:20:09 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:20:18 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:20:20 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:20:23 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:20:49 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:21:09 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 08:21:17 INFO - PROCESS | 17828 | Cycle 1(1): loaded http://localhost:33267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33267/tests/dromaeo/cssquery-mootools.html) 08:21:18 INFO - PROCESS | 17828 | RSS: Main: 216231936 08:21:18 INFO - PROCESS | 17828 | 08:21:23 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 08:21:38 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:21:40 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:21:56 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:21:58 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00009 seconds. 08:22:07 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:22:08 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:22:10 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:22:23 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:22:31 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 08:22:54 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:22:59 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:23:03 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:23:08 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:23:20 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:23:31 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:23:51 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:24:05 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:24:08 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:24:16 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:24:18 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:24:20 INFO - PROCESS | 17828 | Cycle 1(1): loaded http://localhost:33267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33267/tests/dromaeo/cssquery-prototype.html) 08:24:20 INFO - PROCESS | 17828 | RSS: Main: 258715648 08:24:20 INFO - PROCESS | 17828 | 08:24:24 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 08:24:43 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:24:51 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:25:54 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:26:04 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:26:10 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:26:14 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01667 seconds. 08:26:17 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 08:26:28 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 08:26:30 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:26:34 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:26:58 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01666 seconds. 08:27:11 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:27:17 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:27:23 INFO - PROCESS | 17828 | Cycle 1(1): loaded http://localhost:33267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33267/tests/dromaeo/cssquery-yui.html) 08:27:23 INFO - PROCESS | 17828 | RSS: Main: 217825280 08:27:23 INFO - PROCESS | 17828 | 08:27:27 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 08:27:31 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 08:27:40 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:27:44 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 08:27:58 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:28:02 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:28:21 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 08:28:39 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 08:28:41 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:29:07 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 08:29:25 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 08:29:44 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:29:52 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:30:12 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:30:18 INFO - PROCESS | 17828 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 08:30:26 INFO - PROCESS | 17828 | Cycle 1(1): loaded http://localhost:33267/tests/dromaeo/cssquery-yui.html (next: http://localhost:33267/tests/dromaeo/cssquery-dojo.html) 08:30:27 INFO - PROCESS | 17828 | RSS: Main: 304611328 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - PROCESS | 17828 | __start_tp_report 08:30:27 INFO - PROCESS | 17828 | _x_x_mozilla_page_load 08:30:27 INFO - PROCESS | 17828 | _x_x_mozilla_page_load_details 08:30:27 INFO - PROCESS | 17828 | |i|pagename|runs| 08:30:27 INFO - PROCESS | 17828 | |0;dojo.html;3011.879524819007;3046.893358732445;3049;3072.600561926949;3099.504079347305;5052.823151189709;5151.613628977832;5169.819056333029;5251.606129540281;5280.6831590104575;5185.611079169068;5227.895442091165;5272.973635131829;5275.235090911816;5276.630635855492;4692.5072867348945;4761.619070474371;4776.570108690217;4780.952190478103;4857.757112144396;4844.685095468783;4872.756362181876;4887.853364399074;4894.314795928555;4903.607711383099;4619.607333376667;4653.022865198279;4690.179218636725;4753.809847606091;4833.395825521828;4717.905641887178;4758.072175925696;4800.423949126092;4803.351547541072;4808.374911261549;4670.416197975253;4695.1783437898575;4700.4359476862655;4715.198416269242;4759.357486739315;5089.41471730752;5195.740212989335;5200.193969934666;5202.4537423570355;5272.789088436458;5482.5613950883835;5517.44825517445;5574.219609254707;5574.581906356999;5673.8581535461535;5624.71876406178;5625.8593535161535;5632.718364081779;5634.267545219095;5636.49271565557;5263.289455923439;5263.368395792491;5270;5271.341082364705;5335.359756829205;5551.444855514416;5592.105263157875;5650.74571644277;5706;5717.571182161355;5449.3460784706085;5514.972425137848;5516.7517461714315;5560.110382338807;5585.69278689676;5502.889942201214;5542.473465020817;5560.2493663355735;5564.638298510584;5565.860853478614;5390.380106287827;5450.291462109901;5471.206675032177;5561.5272701820795;5634.3802181759975;5519.503244707996;5587.804426845041;5591.133374326986;5651.350094739076;5656.264685590847;4305.138972205571;4311.288637374798;4312.072904325522;4356.651467882625;4366.585174408426;3811.6569508744346;3837.6929845612285;3877.3990031545154;3894.5910679378258;3922.5881282465502;5230.686158830483;5272.55183309423;5321.946780532146;5331.760070796746;5391.973040134774;5652.463016013472;5654.208410822488;5654.6324488908085;5666.716664166775;5725.169850371756;5295.258663787073;5325.866853328698;5345.545628621533;5372.462753724674;5379.031774280589;4667.789949452283;4683.367745354333;4747.786349614206;4840.7337596432535;4925.113479573711;5774.942250577525;5839.591228613956;5872.442117998783;5880.500157486662;5912.793052243151;5635.718214089361;5636.154576813511;5638.520725738194;5640.492355688008;5641.069223578228;5478.479544443271;5833.300003999378;5840.853978650568;5841.4450627190345;5842.211301474416;5314.388845282842;5427.4301198373605;5464.781408743605;5480.260164877691;5576.470235327637;5539.224508568885;5601.29983752031;5601.831945041656;5602.075657516464;5602.439756024365;5450.509454149145;5466.1254199329505;5469.56243500511;5471.097268950579;5537.418571049981;5260.237265596391;5278.340207474066;5355.27703760003;5398.433164517669;5402.513773760224;5339.385970613429;5371.623986321077;5372.221027951004;5372.301600791872;5424.321959755031;5428.457154284698;5443.537299329601;5459.645123066988;5479.0685583451705;5493.23094766725;5198.272241886063;5259.710715910509;5324.707141107277;5354.411014788292;5371.973140134274 08:30:27 INFO - PROCESS | 17828 | |1;ext.html;554.9248355778263;588.0209451263646;590.6338070396355;598.3597550620832;609.8322961185675;31394.843025784867;32039.51940720891;34248.82875585625;42703.572964270345;43576;44600.553994460046;45161.54838451614;45206.09587808242;45237;45245.09509809802;8875.60059797309;9042.728718138433;9043.186113249807;9045;9046.3667543272;8940.06129356418;9031.95484022579;9045.276377889786;9046.276297896153;9048.095190480964;9045.909540904606;9053.230475409599;9067.183953444188;9078.727638170865;9130.589123489459;9008.423525882945;9056.275497960214;9061.961058761792;9066.592003359865;9067.727968160998;8829.16122968317;8883.156100170474;8893.11068893112;8894.066123057051;8902.554872256425;42425.36361954573;42747.78626106881;42806.78596607028;42817.57182428167;42826;3391.96608033919;3432.2963792422493;3606.8737594184076;3801.4677945087433;4028.979855100735;32524.674753252402;32704.509432358533;33076.007719768444;33896.661033389595;34406.139846503786;4294.26997410441;4336.371226172188;4451.243288640939;4459.375687403766;4461;320097.59804803773;320225;320305;320433;320958;54340.45659543354;54628.72685636626;54803.451965480635;54861.725691371284;54999.450005500235;58662.41337586655;58799;58804;58880.705596471744;58890;11563.30620162793;11810.40947952599;15410.768838467431;15675.608109797346;15677.216139192995;11837.467313970892;11954.58158964432;12036;12108.697282568006;12115.697107572381;15861.444849430214;15864.52406427809;15914.681706365807;15946.9202653986;15953.680926381407;5578.776848925998;6539.280679125292;6649.268580456146;6722.596644201363;6734.1918969722965;949.7625593601599;957.899420560845;1057.9629712960163;1090.9399983000853;1105.182165197748;30694.69305306963;31539.684603153673;31549;31582.526262106087;31952.520712189336;1003.9849402258972;1102.9669109926715;1137.9772404551863;1146.4668928947913;1146.5814977533141;15646.530604081896;16542.42101526441;16574.83425165733;16593.585160371087;17002.57493562646;467445;468421;470003;470217;472231;57010.714946425;57128.42871571314;57148;57181;57333.14000289999;1186.5965571705663;1206.8974137198436;1294.7086905446388;1321.8149459075737;1349.6963183283683;29637.703622963494;32762.508562371106;32800.671993279764;33018.504722428705;33467.33065338631;48692.26961595508;53356.73321633367;54633.72683136718;54646.18030729622;59132.40867591269;434450.2439804871;437460;437604;438115;438920;27024.59463108094;27269;27382.726172738017;27419.17742467729;27812;45456;45839;45924.311135333664;46720;47164.528354716014;38604.80697596494;38969.41545876757;39056.80471597738;40374.798126009184;41096.589034109275;38374;39921.40117898292;41244.79377603093;41451;43660.34509482424;39728.2054358917;39896.60103398929;40276.1944761109;40403.59596404116;41236.38145427761;34295.485567717005;35786.717377126864;36200.637993619726;37231.81384093062;37311;27273.59089613697;27753.44493110167;27825.721742782313;27831.30421739473;28085.297867553476 08:30:27 INFO - PROCESS | 17828 | |2;jquery.html;377.1081392506719;393.5316972802367;397.98408063677454;407.1551530574059;415.57250843353086;12916.935415322921;15052.172130532816;16457.753133703005;18746.531336716587;19229.134688938997;17865.374711885088;17971.82028179718;18463.907680461612;18480.075996200205;18493.907530462362;5615.494605485505;5822.243108395903;5827.213326200972;5830.387809280028;5831.416858314177;5459.208414779854;5666.093425051992;5722.141678748179;5729.39841316662;5731.684757338345;5713.74288157034;5725.2843394575675;5733.312002559699;5733.799317023905;5734.627249228808;5490.121580547113;5761.884762304751;5796.014677504813;5863.648181109147;5873.794417195378;5555.2778138842095;5687.402822703598;5688.179782469611;5695.373508914016;5695.40198279183;12424.937875310565;12557.937210313981;18533.629327413375;22635.320940371814;22956.65565016526;2142.4750936020773;2270.035235025124;2301.1945818963395;2348.0255693887016;2822.449622323648;11597.536098556047;13113.86886131136;14318;14760.335784889705;14783.556493305217;1801.2164708351777;1817.318505560415;1873.8126187381154;1895.75355203824;1919.7137917595244;74044;75919.6204018982;76278;78130;78982;11094.114000619915;11138.832917506244;11244.550217991353;11259.437028148559;11329.660110196708;15486.670365871392;15500.457483988006;15519.844801552066;15521.767173492406;15540.533783986499;7624.733134340271;7709.113451952985;7718.922810771933;7844.450888437868;8032.277095061356;7290.453216008715;7298.781036568911;7518.849623007509;7522.924770752332;7525.473216874766;4614.10025045113;4633.582977532038;4646.512116227815;4780.593649539828;4805.639577031737;1887.7923428422862;2006.2677122850055;2024.2510271199455;2027.4525878013148;2031.1164643380177;871.1027641529236;933.3186773655269;956.268454632207;966.5070813884969;978.8972157923458;17646.2059207336;17668.469945901645;17782.288708451775;18475.353362632242;18979.43061708151;920.3465539467053;926.916577508014;939.5302348825587;954.1841725324886;983.9262055345878;9846.310758246997;10457.163426925828;11073.946122970696;11103.722406939729;11454.54181832734;413512;427636;463871;466267;466725;62160.68919655463;63908;92496.6752992234;92950;93797.53101234586;1042.4370839746603;1045.7281106912258;1066.648006157966;1068.7381591509975;1071.8874518175635;16566.254518546935;16748.1625918706;16772.1613919306;17523.912380438527;17715.379961701277;41648.336266197264;41865.37201942034;42508.78745606252;43647.56352436562;43957;57078.7146064267;58033.4196658028;58374.416255838594;58389;58812.411875880694;7673.386129109546;8251.793705157419;8462.19609137131;8485.40602157843;8627.6980305689;12524.812127817908;12970.351482425653;13045.673858153623;13077;13328.733425331633;11127.610533631285;11265.7746845061;11435.371054591744;11571.826422603499;12133.453994570089;11008.449577521253;11248.662540123809;11298.435078246219;11395.487203076047;11873.525058997544;11570.132240082195;11663.533458661559;11697.532098715956;11725.824112638487;12036.578719744766;7706.499077559941;7767.72812951544;7899.68401263943;8196.057453392697;8203.671853126047;10933.453327333442;11499.080073593925;11643.941780291383;11748.35384053851;11793 08:30:27 INFO - PROCESS | 17828 | |3;mootools.html;1330.7671157547427;1442.3221086089538;1499.8725108365788;1611.8226995030545;1781.8752687311885;7914.92085079149;8820.867686984693;9026;9126.863097053541;9303.209227215679;9140.634374625024;9160.450372977626;9175.403598766075;9183.770405739842;9195.264378849693;2358.6579945907874;2380.9761902381015;2388.2477019738726;2388.4984153327737;2389.6176611742126;2357.375295546673;2361;2396;2400.3999000249937;2406.3743426709007;2355.882205889707;2365.73976862545;2370.9407264818346;2405.915792947247;2406.927792166238;2283.041122728458;2294.300238427279;2300.5283916797016;2303.7350704668925;2347.9060837566476;2236.7986881180773;2314.143766806276;2318.629019356895;2322.4890524084676;2324.8721320327377;7990.880136797895;8208.425410221287;9172.266218702554;9245.583948722324;9250.398724082914;5063.468335824722;5252.212168174804;5257.447967963385;5325.973370133124;5329.973350133225;3387.3902697514436;3417.419038763416;3436.9484457733156;3469.60099588548;3486.808225547594;1170.2978213072174;1174.7826652069396;1175.2419689300439;1189.4409627475072;1194.8625908020601;13055.34723263833;13067.673308167277;13081.345932703327;13126.540571079966;13165.539206127836;4573.588377046081;4642.303654451859;4807.471178170398;4900.240462728283;4903.190973489405;10951.945240273906;10954.507047182897;10959.12327013837;10966.177536684781;10967.616133435291;5419.214213938957;5419.566434685215;5421.1055175895535;5422.593305502024;5427;4853.878653033702;4914.0908931847725;4936.308916751657;4989.525995030466;4993.90012199754;4048.2308361411237;4059.878203653895;4077.91844163121;4144.585541445831;4200.4539409876525;1318.9142705724098;1320.9405576748877;1325.2247435250295;1345.643404497809;1350.4058214385639;871.4945331707594;876.4390789894472;888.338188049907;901.6663834381321;991.1426615977127;5948.702564871739;5975.25309336333;6022.096685497123;6053.878922421527;6354.650494222862;966.7486453522135;997.0777031246068;1012.5342342522522;1042.9634962776418;1045.6444808764904;3975.866877939773;4085.264652362544;4101.8974525637095;4127.277726397869;4169.8957526062095;10415.843762343571;10591.682249532527;10943.23397362193;11055.613053543088;11471.827922581168;10822.67531974042;11147.665570032912;11221.158413119048;11226.158038147172;11237.651481822233;7867;8076.232757887991;8110.188981101961;8115.3507719382305;8122.390820688472;6033.78881739137;6140.631562106288;6197.225346831646;6248.0940263660605;6389.9041514376395;10313.9484302578;10495.21285903545;10566.894331056592;10574.629887953884;10657;10836.241463097824;10848.67453976382;10920.126389888948;10953.83569246446;11006.779864402828;7637.732679356196;7698.923010770045;7703.3067023968115;7757.7672669819995;7832.921670783448;8639;8645.783855403664;8690.00064992534;8697.739067827975;8702.216800487986;7824.413169012233;7839.882401764092;7853.607319634109;7860.84278314442;8096.352291816523;7389.408847292311;7842.490238134731;8009.959950200212;8077.798055048671;8092.9190708092165;7126.679299431435;7354.852902941804;7438.702451901863;7442.4418168636485;7444.255574442729;4805.519448055166;5162.457941916045;5206.583473322201;5226.163813789775;5379.892402151857;5009.198528235464;5042.974785126051;5072.365954255718;5200.297959775382;5245.344331958505 08:30:27 INFO - PROCESS | 17828 | |4;prototype.html;319.45851781230823;328.62372583392016;337.44658759634206;337.6033161035782;338.8695352289371;5116.232565115234;5187.247849061884;5229.163333866572;5261.316028916241;5269.288646032789;5086.186210206359;5188.533032027126;5202.647311698957;5203.661761985478;5227.555657769085;4098.836046558149;4100.466939297887;4101.3847922811665;4102.44616976708;4113.773742444165;4068.7151899367054;4124.6700263978955;4129.112240868213;4132.421460995463;4142.378643203513;4102.425660407545;4143;4144.171165766858;4150.273702102135;4163.084121493267;4131.235721391552;4134.131832315217;4136.420901073852;4138.441310423084;4158.334666453383;3704.388775852008;3708.684761795251;3712.832922518466;3714.4428335749585;3730.742900545158;4917.59038071346;5100.209467532502;5124.925044972986;5125.7441926727815;5140.1518749406305;4639.6752227344095;4668;4678.298255261704;4679.251319788851;4699.929501057487;5444.265024221719;5444.891102177974;5449.591280653967;5451.48210919962;5458.399576046631;3855.496356420998;4027.7784721840285;4039.4950631171105;4054.331035379159;4057.7159598828093;5635.3237611486475;5646.802361917355;5646.943530564683;5663.971680141614;5668.404817494186;4131.462909821704;4133.834646614162;4137.386419296477;4143.70994030421;4160.9375859362135;4000.959990400117;4186.3092589722355;4190.874273771792;4198.454200953856;4216.367544868294;4696.81212751493;4698.989717210748;4709.293605959065;4729.810807567659;4746.620270378361;5495.285612870382;5516;5516.172574113915;5518.392976772552;5519.31008623922;1333.9866601334056;1353.3842101843638;1434.2828585707146;1446.146773403697;1449.6883170118265;901.5221932375852;924;937.7727896246415;938.3853575907791;939.4457270210609;4622.2835460503675;4634.698744581591;4636.39726835516;4644.883877903012;4645.953540464619;4859.88222708779;4863.610911127102;4895.853124406273;4940.752962351868;4957.652964292465;4643.930341044887;4690.859274221779;4696.459907110658;4706.117552978836;4707.03505781314;2805.7895657825743;2922.956155657667;2926.809757365764;2958.2160727407254;2971.450281697893;5770.24986751728;5772;5780.971095144498;5784.363719990798;5793.7392817323325;5523.309586301712;5730.971345143331;5739.196512488255;5752.395998420106;5761.164631128547;5742.16738572913;5744.396838331999;5788.18965344852;5795.9420405796245;5815.720541480868;5574.275344205227;5575.553955683617;5591.245181900392;5592.384837667934;5597.3003374578175;5358.169483730028;5527.2538207343105;5595.524380427709;5606.159076138611;5610.635308704921;5736.913946290892;5740.1963725077685;5742.052561327334;5743.8564035899435;5809.448102430262;3880.5149356330458;3945.1123497214044;3959.0894094359037;3974.642282194616;3978.5822488639433;5324.680519168863;5476.397596264328;5476.767727261335;5508.944910551004;5511.944880551143;5268.6575372600655;5337.172738225581;5423.105187643994;5427.3215848019;5444.891102178013;5412.918806217831;5431.37539182999;5440.564754819684;5444.3194600674915;5444.673319600836;5126.53861152498;5295.814646487354;5326.520613144836;5340;5391.627683819107;5478.342598888159;5495.53287970527;5511.972440137773;5516.420775818481;5533.426956465263;5384.60769715077;5387.084195686821;5393.217983392465;5394.622376433612;5409.432009639088 08:30:27 INFO - PROCESS | 17828 | |5;yui.html;704.8237940514871;731.1445608637894;736.694271877171;805.66162211871;815.8776183572467;16631.750523742154;17281.48155555334;17283.999999999985;17293.30826766931;17322;16696.49910502684;16827.326906923707;16839;16846.83153168468;16851.74722379165;663.8506336074392;804.4569915307159;821.8356328734247;823.8475881961826;824.2375802382802;671.4091599392527;798.9880151797728;812.191869090256;818.8771684247351;823.3577809308749;845.9407841451101;851.6125163050797;852.1052894460822;852.2031900173358;855.0765173612515;681.9659017049128;847.0428415890067;847.2713466418875;847.771101802516;847.8134810341714;509.7935336188812;602.220124938206;612.6844674992383;614.7633161232912;623.0840664223584;15769.448069317634;15787.368505259776;15840.841591584052;15850.76223856643;15961.36154553828;13973.441062357493;14127.646808829759;14529.491467798576;14560.271986400638;14796.556103316918;13271.535496257682;13481.932590337083;13565.457381704819;13780.379882905294;13953.302334883318;4410.338449232609;4490.550944905516;4502.234620114588;4525.162844873709;4535.070310586322;77625.61187194084;78640.60679696564;78974;79277;79408.60295698485;10646.307989980627;11049.17131215162;11166.776664466664;11192.720181995515;11532.077433805276;20296.59406811885;20423;20456.284030058876;20469.385918422173;20500.89749551243;7626.885596716053;7908.090569584571;8015.7595272141925;8203.097659257477;8319.251267385964;4414.779261036935;4473.172463094338;4580.862574122782;4603.631709463235;4607.493175750665;3342.3983682937305;3445.0870519312402;3447.500112483676;3448.9655103449145;3450.3099380124077;583.0526687594252;593.4629160609655;595.9702014899237;613.588745884464;619.127030886457;636.723025483916;642.4154019841922;663.2306524431636;667.2626747444101;740.6000759589762;8802.779930501789;9206.769830754285;9510.809783804285;9521.333506654468;9683.32216744821;588.7380115848406;600.7446835095067;651.1307404614797;698.9091418115612;704.5878161275608;4483.932741008887;4553.203189441834;4684.484706682262;4784.976075119602;4819.807207711722;74401;89878.55060724655;119491.40254298846;121703;127516.36241818732;67403;106726.46636766767;111730;115041.4247928755;115575.42212289052;95238.5238073819;95452.52273738587;98449.50775246222;98557;99186;8872;8875.201231889161;8962.058983806606;8977.740478759688;9271.72184834429;51867.55390683924;53877.342878997144;54095.72952135214;54890.725546372014;55423.722881385336;72129;80420;81166.59416702879;83408.16591834003;84150.57924710338;11322.26405283654;11364.374959377314;11477.827832582685;11666.183367164216;11987.700307492383;12756.681082973;12787.61637150887;12789.872101279241;13113.606591802261;13448.663783405493;11884.702882428008;12019.21875078117;12238.204516706386;12323.815142772686;12815.16701414405;11736.178467507192;11938.164328496921;12058.638240852788;12443.066769992467;12620.116591838483;11721.765564688489;12051.397430128634;12407.441665125272;12448.25310481374;13000.934995324962;8833.33749968742;8965.910340896768;9330.300227483101;9393.295502837178;9446.149846513847;11496.770064598828;11621.302721836379;12034.338111403957;12094.516219351128;12163.939180304042 08:30:27 INFO - PROCESS | 17828 | __end_tp_report 08:30:27 INFO - PROCESS | 17828 | __start_cc_report 08:30:27 INFO - PROCESS | 17828 | _x_x_mozilla_cycle_collect,1021 08:30:27 INFO - PROCESS | 17828 | __end_cc_report 08:30:27 INFO - PROCESS | 17828 | __startTimestamp1474126227051__endTimestamp 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - PROCESS | 17828 | ------- Summary: start ------- 08:30:27 INFO - PROCESS | 17828 | Number of tests: 6 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - PROCESS | 17828 | [#0] dojo.html Cycles:165 Average:5193.29 Median:5371.97 stddev:578.46 (10.8%) stddev-sans-first:554.35 08:30:27 INFO - PROCESS | 17828 | Values: 3011.9 3046.9 3049.0 3072.6 3099.5 5052.8 5151.6 5169.8 5251.6 5280.7 5185.6 5227.9 5273.0 5275.2 5276.6 4692.5 4761.6 4776.6 4781.0 4857.8 4844.7 4872.8 4887.9 4894.3 4903.6 4619.6 4653.0 4690.2 4753.8 4833.4 4717.9 4758.1 4800.4 4803.4 4808.4 4670.4 4695.2 4700.4 4715.2 4759.4 5089.4 5195.7 5200.2 5202.5 5272.8 5482.6 5517.4 5574.2 5574.6 5673.9 5624.7 5625.9 5632.7 5634.3 5636.5 5263.3 5263.4 5270.0 5271.3 5335.4 5551.4 5592.1 5650.7 5706.0 5717.6 5449.3 5515.0 5516.8 5560.1 5585.7 5502.9 5542.5 5560.2 5564.6 5565.9 5390.4 5450.3 5471.2 5561.5 5634.4 5519.5 5587.8 5591.1 5651.4 5656.3 4305.1 4311.3 4312.1 4356.7 4366.6 3811.7 3837.7 3877.4 3894.6 3922.6 5230.7 5272.6 5321.9 5331.8 5392.0 5652.5 5654.2 5654.6 5666.7 5725.2 5295.3 5325.9 5345.5 5372.5 5379.0 4667.8 4683.4 4747.8 4840.7 4925.1 5774.9 5839.6 5872.4 5880.5 5912.8 5635.7 5636.2 5638.5 5640.5 5641.1 5478.5 5833.3 5840.9 5841.4 5842.2 5314.4 5427.4 5464.8 5480.3 5576.5 5539.2 5601.3 5601.8 5602.1 5602.4 5450.5 5466.1 5469.6 5471.1 5537.4 5260.2 5278.3 5355.3 5398.4 5402.5 5339.4 5371.6 5372.2 5372.3 5424.3 5428.5 5443.5 5459.6 5479.1 5493.2 5198.3 5259.7 5324.7 5354.4 5372.0 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - PROCESS | 17828 | [#1] ext.html Cycles:180 Average:57061.95 Median:42564.47 stddev:109532.94 (257.3%) stddev-sans-first:109758.05 08:30:27 INFO - PROCESS | 17828 | Values: 554.9 588.0 590.6 598.4 609.8 31394.8 32039.5 34248.8 42703.6 43576.0 44600.6 45161.5 45206.1 45237.0 45245.1 8875.6 9042.7 9043.2 9045.0 9046.4 8940.1 9032.0 9045.3 9046.3 9048.1 9045.9 9053.2 9067.2 9078.7 9130.6 9008.4 9056.3 9062.0 9066.6 9067.7 8829.2 8883.2 8893.1 8894.1 8902.6 42425.4 42747.8 42806.8 42817.6 42826.0 3392.0 3432.3 3606.9 3801.5 4029.0 32524.7 32704.5 33076.0 33896.7 34406.1 4294.3 4336.4 4451.2 4459.4 4461.0 320097.6 320225.0 320305.0 320433.0 320958.0 54340.5 54628.7 54803.5 54861.7 54999.5 58662.4 58799.0 58804.0 58880.7 58890.0 11563.3 11810.4 15410.8 15675.6 15677.2 11837.5 11954.6 12036.0 12108.7 12115.7 15861.4 15864.5 15914.7 15946.9 15953.7 5578.8 6539.3 6649.3 6722.6 6734.2 949.8 957.9 1058.0 1090.9 1105.2 30694.7 31539.7 31549.0 31582.5 31952.5 1004.0 1103.0 1138.0 1146.5 1146.6 15646.5 16542.4 16574.8 16593.6 17002.6 467445.0 468421.0 470003.0 470217.0 472231.0 57010.7 57128.4 57148.0 57181.0 57333.1 1186.6 1206.9 1294.7 1321.8 1349.7 29637.7 32762.5 32800.7 33018.5 33467.3 48692.3 53356.7 54633.7 54646.2 59132.4 434450.2 437460.0 437604.0 438115.0 438920.0 27024.6 27269.0 27382.7 27419.2 27812.0 45456.0 45839.0 45924.3 46720.0 47164.5 38604.8 38969.4 39056.8 40374.8 41096.6 38374.0 39921.4 41244.8 41451.0 43660.3 39728.2 39896.6 40276.2 40403.6 41236.4 34295.5 35786.7 36200.6 37231.8 37311.0 27273.6 27753.4 27825.7 27831.3 28085.3 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - PROCESS | 17828 | [#2] jquery.html Cycles:180 Average:27184.04 Median:395.76 stddev:73902.05 (18673.5%) stddev-sans-first:74081.95 08:30:27 INFO - PROCESS | 17828 | Values: 377.1 393.5 398.0 407.2 415.6 12916.9 15052.2 16457.8 18746.5 19229.1 17865.4 17971.8 18463.9 18480.1 18493.9 5615.5 5822.2 5827.2 5830.4 5831.4 5459.2 5666.1 5722.1 5729.4 5731.7 5713.7 5725.3 5733.3 5733.8 5734.6 5490.1 5761.9 5796.0 5863.6 5873.8 5555.3 5687.4 5688.2 5695.4 5695.4 12424.9 12557.9 18533.6 22635.3 22956.7 2142.5 2270.0 2301.2 2348.0 2822.4 11597.5 13113.9 14318.0 14760.3 14783.6 1801.2 1817.3 1873.8 1895.8 1919.7 74044.0 75919.6 76278.0 78130.0 78982.0 11094.1 11138.8 11244.6 11259.4 11329.7 15486.7 15500.5 15519.8 15521.8 15540.5 7624.7 7709.1 7718.9 7844.5 8032.3 7290.5 7298.8 7518.8 7522.9 7525.5 4614.1 4633.6 4646.5 4780.6 4805.6 1887.8 2006.3 2024.3 2027.5 2031.1 871.1 933.3 956.3 966.5 978.9 17646.2 17668.5 17782.3 18475.4 18979.4 920.3 926.9 939.5 954.2 983.9 9846.3 10457.2 11073.9 11103.7 11454.5 413512.0 427636.0 463871.0 466267.0 466725.0 62160.7 63908.0 92496.7 92950.0 93797.5 1042.4 1045.7 1066.6 1068.7 1071.9 16566.3 16748.2 16772.2 17523.9 17715.4 41648.3 41865.4 42508.8 43647.6 43957.0 57078.7 58033.4 58374.4 58389.0 58812.4 7673.4 8251.8 8462.2 8485.4 8627.7 12524.8 12970.4 13045.7 13077.0 13328.7 11127.6 11265.8 11435.4 11571.8 12133.5 11008.4 11248.7 11298.4 11395.5 11873.5 11570.1 11663.5 11697.5 11725.8 12036.6 7706.5 7767.7 7899.7 8196.1 8203.7 10933.5 11499.1 11643.9 11748.4 11793.0 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - PROCESS | 17828 | [#3] mootools.html Cycles:180 Average:5949.29 Median:4806.50 stddev:3429.97 (71.4%) stddev-sans-first:3422.03 08:30:27 INFO - PROCESS | 17828 | Values: 1330.8 1442.3 1499.9 1611.8 1781.9 7914.9 8820.9 9026.0 9126.9 9303.2 9140.6 9160.5 9175.4 9183.8 9195.3 2358.7 2381.0 2388.2 2388.5 2389.6 2357.4 2361.0 2396.0 2400.4 2406.4 2355.9 2365.7 2370.9 2405.9 2406.9 2283.0 2294.3 2300.5 2303.7 2347.9 2236.8 2314.1 2318.6 2322.5 2324.9 7990.9 8208.4 9172.3 9245.6 9250.4 5063.5 5252.2 5257.4 5326.0 5330.0 3387.4 3417.4 3436.9 3469.6 3486.8 1170.3 1174.8 1175.2 1189.4 1194.9 13055.3 13067.7 13081.3 13126.5 13165.5 4573.6 4642.3 4807.5 4900.2 4903.2 10951.9 10954.5 10959.1 10966.2 10967.6 5419.2 5419.6 5421.1 5422.6 5427.0 4853.9 4914.1 4936.3 4989.5 4993.9 4048.2 4059.9 4077.9 4144.6 4200.5 1318.9 1320.9 1325.2 1345.6 1350.4 871.5 876.4 888.3 901.7 991.1 5948.7 5975.3 6022.1 6053.9 6354.7 966.7 997.1 1012.5 1043.0 1045.6 3975.9 4085.3 4101.9 4127.3 4169.9 10415.8 10591.7 10943.2 11055.6 11471.8 10822.7 11147.7 11221.2 11226.2 11237.7 7867.0 8076.2 8110.2 8115.4 8122.4 6033.8 6140.6 6197.2 6248.1 6389.9 10313.9 10495.2 10566.9 10574.6 10657.0 10836.2 10848.7 10920.1 10953.8 11006.8 7637.7 7698.9 7703.3 7757.8 7832.9 8639.0 8645.8 8690.0 8697.7 8702.2 7824.4 7839.9 7853.6 7860.8 8096.4 7389.4 7842.5 8010.0 8077.8 8092.9 7126.7 7354.9 7438.7 7442.4 7444.3 4805.5 5162.5 5206.6 5226.2 5379.9 5009.2 5043.0 5072.4 5200.3 5245.3 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - PROCESS | 17828 | [#4] prototype.html Cycles:180 Average:4565.94 Median:5126.14 stddev:1321.66 (25.8%) stddev-sans-first:1286.37 08:30:27 INFO - PROCESS | 17828 | Values: 319.5 328.6 337.4 337.6 338.9 5116.2 5187.2 5229.2 5261.3 5269.3 5086.2 5188.5 5202.6 5203.7 5227.6 4098.8 4100.5 4101.4 4102.4 4113.8 4068.7 4124.7 4129.1 4132.4 4142.4 4102.4 4143.0 4144.2 4150.3 4163.1 4131.2 4134.1 4136.4 4138.4 4158.3 3704.4 3708.7 3712.8 3714.4 3730.7 4917.6 5100.2 5124.9 5125.7 5140.2 4639.7 4668.0 4678.3 4679.3 4699.9 5444.3 5444.9 5449.6 5451.5 5458.4 3855.5 4027.8 4039.5 4054.3 4057.7 5635.3 5646.8 5646.9 5664.0 5668.4 4131.5 4133.8 4137.4 4143.7 4160.9 4001.0 4186.3 4190.9 4198.5 4216.4 4696.8 4699.0 4709.3 4729.8 4746.6 5495.3 5516.0 5516.2 5518.4 5519.3 1334.0 1353.4 1434.3 1446.1 1449.7 901.5 924.0 937.8 938.4 939.4 4622.3 4634.7 4636.4 4644.9 4646.0 4859.9 4863.6 4895.9 4940.8 4957.7 4643.9 4690.9 4696.5 4706.1 4707.0 2805.8 2923.0 2926.8 2958.2 2971.5 5770.2 5772.0 5781.0 5784.4 5793.7 5523.3 5731.0 5739.2 5752.4 5761.2 5742.2 5744.4 5788.2 5795.9 5815.7 5574.3 5575.6 5591.2 5592.4 5597.3 5358.2 5527.3 5595.5 5606.2 5610.6 5736.9 5740.2 5742.1 5743.9 5809.4 3880.5 3945.1 3959.1 3974.6 3978.6 5324.7 5476.4 5476.8 5508.9 5511.9 5268.7 5337.2 5423.1 5427.3 5444.9 5412.9 5431.4 5440.6 5444.3 5444.7 5126.5 5295.8 5326.5 5340.0 5391.6 5478.3 5495.5 5512.0 5516.4 5533.4 5384.6 5387.1 5393.2 5394.6 5409.4 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - PROCESS | 17828 | [#5] yui.html Cycles:180 Average:21168.18 Median:4802.39 stddev:31172.70 (649.1%) stddev-sans-first:31222.28 08:30:27 INFO - PROCESS | 17828 | Values: 704.8 731.1 736.7 805.7 815.9 16631.8 17281.5 17284.0 17293.3 17322.0 16696.5 16827.3 16839.0 16846.8 16851.7 663.9 804.5 821.8 823.8 824.2 671.4 799.0 812.2 818.9 823.4 845.9 851.6 852.1 852.2 855.1 682.0 847.0 847.3 847.8 847.8 509.8 602.2 612.7 614.8 623.1 15769.4 15787.4 15840.8 15850.8 15961.4 13973.4 14127.6 14529.5 14560.3 14796.6 13271.5 13481.9 13565.5 13780.4 13953.3 4410.3 4490.6 4502.2 4525.2 4535.1 77625.6 78640.6 78974.0 79277.0 79408.6 10646.3 11049.2 11166.8 11192.7 11532.1 20296.6 20423.0 20456.3 20469.4 20500.9 7626.9 7908.1 8015.8 8203.1 8319.3 4414.8 4473.2 4580.9 4603.6 4607.5 3342.4 3445.1 3447.5 3449.0 3450.3 583.1 593.5 596.0 613.6 619.1 636.7 642.4 663.2 667.3 740.6 8802.8 9206.8 9510.8 9521.3 9683.3 588.7 600.7 651.1 698.9 704.6 4483.9 4553.2 4684.5 4785.0 4819.8 74401.0 89878.6 119491.4 121703.0 127516.4 67403.0 106726.5 111730.0 115041.4 115575.4 95238.5 95452.5 98449.5 98557.0 99186.0 8872.0 8875.2 8962.1 8977.7 9271.7 51867.6 53877.3 54095.7 54890.7 55423.7 72129.0 80420.0 81166.6 83408.2 84150.6 11322.3 11364.4 11477.8 11666.2 11987.7 12756.7 12787.6 12789.9 13113.6 13448.7 11884.7 12019.2 12238.2 12323.8 12815.2 11736.2 11938.2 12058.6 12443.1 12620.1 11721.8 12051.4 12407.4 12448.3 13000.9 8833.3 8965.9 9330.3 9393.3 9446.1 11496.8 11621.3 12034.3 12094.5 12163.9 08:30:27 INFO - PROCESS | 17828 | -------- Summary: end -------- 08:30:27 INFO - PROCESS | 17828 | 08:30:27 INFO - TEST-INFO | 17828: exit 0 08:30:27 INFO - TEST-OK | dromaeo_css | took 1115818ms 08:30:27 INFO - TEST-START | kraken 08:30:27 INFO - operating with platform_type : linux_ 08:30:27 INFO - Initialising browser for kraken test... 08:30:27 INFO - TEST-INFO | started process 18137 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbcobmJ/profile http://localhost:33267/getInfo.html) 08:30:31 INFO - PROCESS | 18137 | __metrics Screen width/height:1600/1200 08:30:31 INFO - PROCESS | 18137 | colorDepth:24 08:30:31 INFO - PROCESS | 18137 | Browser inner width/height: 1024/697 08:30:31 INFO - PROCESS | 18137 | __metrics 08:30:32 INFO - PROCESS | 18137 | 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" 08:30:32 INFO - PROCESS | 18137 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:30:32 INFO - PROCESS | 18137 | console.error: PushService: 08:30:32 INFO - PROCESS | 18137 | stateChangeProcessEnqueue: Error transitioning state 08:30:32 INFO - PROCESS | 18137 | UnknownError 08:30:33 INFO - TEST-INFO | 18137: exit 0 08:30:33 INFO - Browser initialized. 08:30:33 INFO - Running cycle 1/1 for kraken test... 08:30:33 INFO - TEST-INFO | started process 18219 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbcobmJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:45 INFO - PROCESS | 18219 | RSS: Main: 172535808 08:30:45 INFO - PROCESS | 18219 | 08:30:48 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33267/tests/kraken/driver.html?testName=audio-beat-detection) 08:30:49 INFO - PROCESS | 18219 | RSS: Main: 232181760 08:30:49 INFO - PROCESS | 18219 | 08:30:51 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33267/tests/kraken/driver.html?testName=audio-dft) 08:30:52 INFO - PROCESS | 18219 | RSS: Main: 216391680 08:30:52 INFO - PROCESS | 18219 | 08:30:55 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33267/tests/kraken/driver.html?testName=audio-fft) 08:30:55 INFO - PROCESS | 18219 | RSS: Main: 297283584 08:30:55 INFO - PROCESS | 18219 | 08:30:58 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33267/tests/kraken/driver.html?testName=audio-oscillator) 08:30:58 INFO - PROCESS | 18219 | RSS: Main: 207515648 08:30:58 INFO - PROCESS | 18219 | 08:31:00 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:31:01 INFO - PROCESS | 18219 | RSS: Main: 211431424 08:31:01 INFO - PROCESS | 18219 | 08:31:04 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33267/tests/kraken/driver.html?testName=imaging-darkroom) 08:31:04 INFO - PROCESS | 18219 | RSS: Main: 248512512 08:31:04 INFO - PROCESS | 18219 | 08:31:08 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33267/tests/kraken/driver.html?testName=imaging-desaturate) 08:31:08 INFO - PROCESS | 18219 | RSS: Main: 248152064 08:31:08 INFO - PROCESS | 18219 | 08:31:11 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33267/tests/kraken/driver.html?testName=json-parse-financial) 08:31:12 INFO - PROCESS | 18219 | RSS: Main: 248127488 08:31:12 INFO - PROCESS | 18219 | 08:31:13 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:31:14 INFO - PROCESS | 18219 | RSS: Main: 210006016 08:31:14 INFO - PROCESS | 18219 | 08:31:16 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33267/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:31:16 INFO - PROCESS | 18219 | RSS: Main: 217706496 08:31:16 INFO - PROCESS | 18219 | 08:31:18 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:31:19 INFO - PROCESS | 18219 | RSS: Main: 221728768 08:31:19 INFO - PROCESS | 18219 | 08:31:21 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:31:22 INFO - PROCESS | 18219 | RSS: Main: 221892608 08:31:22 INFO - PROCESS | 18219 | 08:31:25 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:31:26 INFO - PROCESS | 18219 | RSS: Main: 221016064 08:31:26 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | Cycle 1(1): loaded http://localhost:33267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33267/tests/kraken/driver.html?testName=ai-astar) 08:31:27 INFO - PROCESS | 18219 | RSS: Main: 271802368 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | __start_tp_report 08:31:27 INFO - PROCESS | 18219 | _x_x_mozilla_page_load 08:31:27 INFO - PROCESS | 18219 | _x_x_mozilla_page_load_details 08:31:27 INFO - PROCESS | 18219 | |i|pagename|runs| 08:31:27 INFO - PROCESS | 18219 | |0;ai-astar;108;110;110;110;111;111;111;110;111;112 08:31:27 INFO - PROCESS | 18219 | |1;audio-beat-detection;149;118;119;160;119;121;124;124;152;117 08:31:27 INFO - PROCESS | 18219 | |2;audio-dft;159;162;160;159;160;158;161;161;163;160 08:31:27 INFO - PROCESS | 18219 | |3;audio-fft;75;75;75;107;76;77;76;76;107;75 08:31:27 INFO - PROCESS | 18219 | |4;audio-oscillator;82;82;112;82;82;83;104;82;81;110 08:31:27 INFO - PROCESS | 18219 | |5;imaging-gaussian-blur;117;116;116;117;116;117;117;116;116;117 08:31:27 INFO - PROCESS | 18219 | |6;imaging-darkroom;146;146;145;146;145;146;145;146;145;145 08:31:27 INFO - PROCESS | 18219 | |7;imaging-desaturate;96;95;95;94;95;94;95;95;95;95 08:31:27 INFO - PROCESS | 18219 | |8;json-parse-financial;51;50;48;48;50;52;51;50;51;51 08:31:27 INFO - PROCESS | 18219 | |9;json-stringify-tinderbox;62;62;60;60;60;61;61;61;60;59 08:31:27 INFO - PROCESS | 18219 | |10;stanford-crypto-aes;85;78;78;79;78;78;84;79;78;79 08:31:27 INFO - PROCESS | 18219 | |11;stanford-crypto-ccm;130;132;126;124;128;127;139;123;167;130 08:31:27 INFO - PROCESS | 18219 | |12;stanford-crypto-pbkdf2;170;169;164;165;171;170;169;164;165;163 08:31:27 INFO - PROCESS | 18219 | |13;stanford-crypto-sha256-iterative;66;67;66;70;65;66;69;65;64;65 08:31:27 INFO - PROCESS | 18219 | __end_tp_report 08:31:27 INFO - PROCESS | 18219 | __start_cc_report 08:31:27 INFO - PROCESS | 18219 | _x_x_mozilla_cycle_collect,1643 08:31:27 INFO - PROCESS | 18219 | __end_cc_report 08:31:27 INFO - PROCESS | 18219 | __startTimestamp1474126287957__endTimestamp 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | ------- Summary: start ------- 08:31:27 INFO - PROCESS | 18219 | Number of tests: 14 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.07 (1.0%) stddev-sans-first:0.71 08:31:27 INFO - PROCESS | 18219 | Values: 108.0 110.0 110.0 110.0 111.0 111.0 111.0 110.0 111.0 112.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:124.00 stddev:16.51 (13.3%) stddev-sans-first:16.06 08:31:27 INFO - PROCESS | 18219 | Values: 149.0 118.0 119.0 160.0 119.0 121.0 124.0 124.0 152.0 117.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:1.49 (0.9%) stddev-sans-first:1.51 08:31:27 INFO - PROCESS | 18219 | Values: 159.0 162.0 160.0 159.0 160.0 158.0 161.0 161.0 163.0 160.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.50 stddev:13.25 (17.5%) stddev-sans-first:13.81 08:31:27 INFO - PROCESS | 18219 | Values: 75.0 75.0 75.0 107.0 76.0 77.0 76.0 76.0 107.0 75.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:82.00 stddev:13.04 (15.9%) stddev-sans-first:13.50 08:31:27 INFO - PROCESS | 18219 | Values: 82.0 82.0 112.0 82.0 82.0 83.0 104.0 82.0 81.0 110.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 08:31:27 INFO - PROCESS | 18219 | Values: 117.0 116.0 116.0 117.0 116.0 117.0 117.0 116.0 116.0 117.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 08:31:27 INFO - PROCESS | 18219 | Values: 146.0 146.0 145.0 146.0 145.0 146.0 145.0 146.0 145.0 145.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 08:31:27 INFO - PROCESS | 18219 | Values: 96.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:51.00 stddev:1.32 (2.6%) stddev-sans-first:1.36 08:31:27 INFO - PROCESS | 18219 | Values: 51.0 50.0 48.0 48.0 50.0 52.0 51.0 50.0 51.0 51.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.97 (1.6%) stddev-sans-first:0.88 08:31:27 INFO - PROCESS | 18219 | Values: 62.0 62.0 60.0 60.0 60.0 61.0 61.0 61.0 60.0 59.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:79.00 stddev:2.63 (3.3%) stddev-sans-first:1.94 08:31:27 INFO - PROCESS | 18219 | Values: 85.0 78.0 78.0 79.0 78.0 78.0 84.0 79.0 78.0 79.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#11] stanford-crypto-ccm Cycles:10 Average:132.60 Median:130.00 stddev:12.91 (9.9%) stddev-sans-first:13.66 08:31:27 INFO - PROCESS | 18219 | Values: 130.0 132.0 126.0 124.0 128.0 127.0 139.0 123.0 167.0 130.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:169.00 stddev:3.06 (1.8%) stddev-sans-first:3.04 08:31:27 INFO - PROCESS | 18219 | Values: 170.0 169.0 164.0 165.0 171.0 170.0 169.0 164.0 165.0 163.0 08:31:27 INFO - PROCESS | 18219 | 08:31:27 INFO - PROCESS | 18219 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:1.89 (2.9%) stddev-sans-first:2.00 08:31:27 INFO - PROCESS | 18219 | Values: 66.0 67.0 66.0 70.0 65.0 66.0 69.0 65.0 64.0 65.0 08:31:27 INFO - PROCESS | 18219 | -------- Summary: end -------- 08:31:27 INFO - PROCESS | 18219 | 08:31:29 INFO - TEST-INFO | 18219: exit 0 08:31:29 INFO - TEST-OK | kraken | took 61279ms 08:31:29 INFO - SUITE-END | took 1177s 08:31:29 INFO - Completed test suite (00:19:37) 08:31:29 INFO - javascript benchmark 08:31:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3011.879524819007, 3046.893358732445, 3049.0, 3072.600561926949, 3099.504079347305, 5052.823151189709, 5151.613628977832, 5169.819056333029, 5251.606129540281, 5280.6831590104575, 5185.611079169068, 5227.895442091165, 5272.973635131829, 5275.235090911816, 5276.630635855492, 4692.5072867348945, 4761.619070474371, 4776.570108690217, 4780.952190478103, 4857.757112144396, 4844.685095468783, 4872.756362181876, 4887.853364399074, 4894.314795928555, 4903.607711383099, 4619.607333376667, 4653.022865198279, 4690.179218636725, 4753.809847606091, 4833.395825521828, 4717.905641887178, 4758.072175925696, 4800.423949126092, 4803.351547541072, 4808.374911261549, 4670.416197975253, 4695.1783437898575, 4700.4359476862655, 4715.198416269242, 4759.357486739315, 5089.41471730752, 5195.740212989335, 5200.193969934666, 5202.4537423570355, 5272.789088436458, 5482.5613950883835, 5517.44825517445, 5574.219609254707, 5574.581906356999, 5673.8581535461535, 5624.71876406178, 5625.8593535161535, 5632.718364081779, 5634.267545219095, 5636.49271565557, 5263.289455923439, 5263.368395792491, 5270.0, 5271.341082364705, 5335.359756829205, 5551.444855514416, 5592.105263157875, 5650.74571644277, 5706.0, 5717.571182161355, 5449.3460784706085, 5514.972425137848, 5516.7517461714315, 5560.110382338807, 5585.69278689676, 5502.889942201214, 5542.473465020817, 5560.2493663355735, 5564.638298510584, 5565.860853478614, 5390.380106287827, 5450.291462109901, 5471.206675032177, 5561.5272701820795, 5634.3802181759975, 5519.503244707996, 5587.804426845041, 5591.133374326986, 5651.350094739076, 5656.264685590847, 4305.138972205571, 4311.288637374798, 4312.072904325522, 4356.651467882625, 4366.585174408426, 3811.6569508744346, 3837.6929845612285, 3877.3990031545154, 3894.5910679378258, 3922.5881282465502, 5230.686158830483, 5272.55183309423, 5321.946780532146, 5331.760070796746, 5391.973040134774, 5652.463016013472, 5654.208410822488, 5654.6324488908085, 5666.716664166775, 5725.169850371756, 5295.258663787073, 5325.866853328698, 5345.545628621533, 5372.462753724674, 5379.031774280589, 4667.789949452283, 4683.367745354333, 4747.786349614206, 4840.7337596432535, 4925.113479573711, 5774.942250577525, 5839.591228613956, 5872.442117998783, 5880.500157486662, 5912.793052243151, 5635.718214089361, 5636.154576813511, 5638.520725738194, 5640.492355688008, 5641.069223578228, 5478.479544443271, 5833.300003999378, 5840.853978650568, 5841.4450627190345, 5842.211301474416, 5314.388845282842, 5427.4301198373605, 5464.781408743605, 5480.260164877691, 5576.470235327637, 5539.224508568885, 5601.29983752031, 5601.831945041656, 5602.075657516464, 5602.439756024365, 5450.509454149145, 5466.1254199329505, 5469.56243500511, 5471.097268950579, 5537.418571049981, 5260.237265596391, 5278.340207474066, 5355.27703760003, 5398.433164517669, 5402.513773760224, 5339.385970613429, 5371.623986321077, 5372.221027951004, 5372.301600791872, 5424.321959755031, 5428.457154284698, 5443.537299329601, 5459.645123066988, 5479.0685583451705, 5493.23094766725, 5198.272241886063, 5259.710715910509, 5324.707141107277, 5354.411014788292, 5371.973140134274], "value": 5155.244287277908, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.9248355778263, 588.0209451263646, 590.6338070396355, 598.3597550620832, 609.8322961185675, 31394.843025784867, 32039.51940720891, 34248.82875585625, 42703.572964270345, 43576.0, 44600.553994460046, 45161.54838451614, 45206.09587808242, 45237.0, 45245.09509809802, 8875.60059797309, 9042.728718138433, 9043.186113249807, 9045.0, 9046.3667543272, 8940.06129356418, 9031.95484022579, 9045.276377889786, 9046.276297896153, 9048.095190480964, 9045.909540904606, 9053.230475409599, 9067.183953444188, 9078.727638170865, 9130.589123489459, 9008.423525882945, 9056.275497960214, 9061.961058761792, 9066.592003359865, 9067.727968160998, 8829.16122968317, 8883.156100170474, 8893.11068893112, 8894.066123057051, 8902.554872256425, 42425.36361954573, 42747.78626106881, 42806.78596607028, 42817.57182428167, 42826.0, 3391.96608033919, 3432.2963792422493, 3606.8737594184076, 3801.4677945087433, 4028.979855100735, 32524.674753252402, 32704.509432358533, 33076.007719768444, 33896.661033389595, 34406.139846503786, 4294.26997410441, 4336.371226172188, 4451.243288640939, 4459.375687403766, 4461.0, 320097.59804803773, 320225.0, 320305.0, 320433.0, 320958.0, 54340.45659543354, 54628.72685636626, 54803.451965480635, 54861.725691371284, 54999.450005500235, 58662.41337586655, 58799.0, 58804.0, 58880.705596471744, 58890.0, 11563.30620162793, 11810.40947952599, 15410.768838467431, 15675.608109797346, 15677.216139192995, 11837.467313970892, 11954.58158964432, 12036.0, 12108.697282568006, 12115.697107572381, 15861.444849430214, 15864.52406427809, 15914.681706365807, 15946.9202653986, 15953.680926381407, 5578.776848925998, 6539.280679125292, 6649.268580456146, 6722.596644201363, 6734.1918969722965, 949.7625593601599, 957.899420560845, 1057.9629712960163, 1090.9399983000853, 1105.182165197748, 30694.69305306963, 31539.684603153673, 31549.0, 31582.526262106087, 31952.520712189336, 1003.9849402258972, 1102.9669109926715, 1137.9772404551863, 1146.4668928947913, 1146.5814977533141, 15646.530604081896, 16542.42101526441, 16574.83425165733, 16593.585160371087, 17002.57493562646, 467445.0, 468421.0, 470003.0, 470217.0, 472231.0, 57010.714946425, 57128.42871571314, 57148.0, 57181.0, 57333.14000289999, 1186.5965571705663, 1206.8974137198436, 1294.7086905446388, 1321.8149459075737, 1349.6963183283683, 29637.703622963494, 32762.508562371106, 32800.671993279764, 33018.504722428705, 33467.33065338631, 48692.26961595508, 53356.73321633367, 54633.72683136718, 54646.18030729622, 59132.40867591269, 434450.2439804871, 437460.0, 437604.0, 438115.0, 438920.0, 27024.59463108094, 27269.0, 27382.726172738017, 27419.17742467729, 27812.0, 45456.0, 45839.0, 45924.311135333664, 46720.0, 47164.528354716014, 38604.80697596494, 38969.41545876757, 39056.80471597738, 40374.798126009184, 41096.589034109275, 38374.0, 39921.40117898292, 41244.79377603093, 41451.0, 43660.34509482424, 39728.2054358917, 39896.60103398929, 40276.1944761109, 40403.59596404116, 41236.38145427761, 34295.485567717005, 35786.717377126864, 36200.637993619726, 37231.81384093062, 37311.0, 27273.59089613697, 27753.44493110167, 27825.721742782313, 27831.30421739473, 28085.297867553476], "value": 19553.021092812523, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.1081392506719, 393.5316972802367, 397.98408063677454, 407.1551530574059, 415.57250843353086, 12916.935415322921, 15052.172130532816, 16457.753133703005, 18746.531336716587, 19229.134688938997, 17865.374711885088, 17971.82028179718, 18463.907680461612, 18480.075996200205, 18493.907530462362, 5615.494605485505, 5822.243108395903, 5827.213326200972, 5830.387809280028, 5831.416858314177, 5459.208414779854, 5666.093425051992, 5722.141678748179, 5729.39841316662, 5731.684757338345, 5713.74288157034, 5725.2843394575675, 5733.312002559699, 5733.799317023905, 5734.627249228808, 5490.121580547113, 5761.884762304751, 5796.014677504813, 5863.648181109147, 5873.794417195378, 5555.2778138842095, 5687.402822703598, 5688.179782469611, 5695.373508914016, 5695.40198279183, 12424.937875310565, 12557.937210313981, 18533.629327413375, 22635.320940371814, 22956.65565016526, 2142.4750936020773, 2270.035235025124, 2301.1945818963395, 2348.0255693887016, 2822.449622323648, 11597.536098556047, 13113.86886131136, 14318.0, 14760.335784889705, 14783.556493305217, 1801.2164708351777, 1817.318505560415, 1873.8126187381154, 1895.75355203824, 1919.7137917595244, 74044.0, 75919.6204018982, 76278.0, 78130.0, 78982.0, 11094.114000619915, 11138.832917506244, 11244.550217991353, 11259.437028148559, 11329.660110196708, 15486.670365871392, 15500.457483988006, 15519.844801552066, 15521.767173492406, 15540.533783986499, 7624.733134340271, 7709.113451952985, 7718.922810771933, 7844.450888437868, 8032.277095061356, 7290.453216008715, 7298.781036568911, 7518.849623007509, 7522.924770752332, 7525.473216874766, 4614.10025045113, 4633.582977532038, 4646.512116227815, 4780.593649539828, 4805.639577031737, 1887.7923428422862, 2006.2677122850055, 2024.2510271199455, 2027.4525878013148, 2031.1164643380177, 871.1027641529236, 933.3186773655269, 956.268454632207, 966.5070813884969, 978.8972157923458, 17646.2059207336, 17668.469945901645, 17782.288708451775, 18475.353362632242, 18979.43061708151, 920.3465539467053, 926.916577508014, 939.5302348825587, 954.1841725324886, 983.9262055345878, 9846.310758246997, 10457.163426925828, 11073.946122970696, 11103.722406939729, 11454.54181832734, 413512.0, 427636.0, 463871.0, 466267.0, 466725.0, 62160.68919655463, 63908.0, 92496.6752992234, 92950.0, 93797.53101234586, 1042.4370839746603, 1045.7281106912258, 1066.648006157966, 1068.7381591509975, 1071.8874518175635, 16566.254518546935, 16748.1625918706, 16772.1613919306, 17523.912380438527, 17715.379961701277, 41648.336266197264, 41865.37201942034, 42508.78745606252, 43647.56352436562, 43957.0, 57078.7146064267, 58033.4196658028, 58374.416255838594, 58389.0, 58812.411875880694, 7673.386129109546, 8251.793705157419, 8462.19609137131, 8485.40602157843, 8627.6980305689, 12524.812127817908, 12970.351482425653, 13045.673858153623, 13077.0, 13328.733425331633, 11127.610533631285, 11265.7746845061, 11435.371054591744, 11571.826422603499, 12133.453994570089, 11008.449577521253, 11248.662540123809, 11298.435078246219, 11395.487203076047, 11873.525058997544, 11570.132240082195, 11663.533458661559, 11697.532098715956, 11725.824112638487, 12036.578719744766, 7706.499077559941, 7767.72812951544, 7899.68401263943, 8196.057453392697, 8203.671853126047, 10933.453327333442, 11499.080073593925, 11643.941780291383, 11748.35384053851, 11793.0], "value": 9023.650113810048, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.7671157547427, 1442.3221086089538, 1499.8725108365788, 1611.8226995030545, 1781.8752687311885, 7914.92085079149, 8820.867686984693, 9026.0, 9126.863097053541, 9303.209227215679, 9140.634374625024, 9160.450372977626, 9175.403598766075, 9183.770405739842, 9195.264378849693, 2358.6579945907874, 2380.9761902381015, 2388.2477019738726, 2388.4984153327737, 2389.6176611742126, 2357.375295546673, 2361.0, 2396.0, 2400.3999000249937, 2406.3743426709007, 2355.882205889707, 2365.73976862545, 2370.9407264818346, 2405.915792947247, 2406.927792166238, 2283.041122728458, 2294.300238427279, 2300.5283916797016, 2303.7350704668925, 2347.9060837566476, 2236.7986881180773, 2314.143766806276, 2318.629019356895, 2322.4890524084676, 2324.8721320327377, 7990.880136797895, 8208.425410221287, 9172.266218702554, 9245.583948722324, 9250.398724082914, 5063.468335824722, 5252.212168174804, 5257.447967963385, 5325.973370133124, 5329.973350133225, 3387.3902697514436, 3417.419038763416, 3436.9484457733156, 3469.60099588548, 3486.808225547594, 1170.2978213072174, 1174.7826652069396, 1175.2419689300439, 1189.4409627475072, 1194.8625908020601, 13055.34723263833, 13067.673308167277, 13081.345932703327, 13126.540571079966, 13165.539206127836, 4573.588377046081, 4642.303654451859, 4807.471178170398, 4900.240462728283, 4903.190973489405, 10951.945240273906, 10954.507047182897, 10959.12327013837, 10966.177536684781, 10967.616133435291, 5419.214213938957, 5419.566434685215, 5421.1055175895535, 5422.593305502024, 5427.0, 4853.878653033702, 4914.0908931847725, 4936.308916751657, 4989.525995030466, 4993.90012199754, 4048.2308361411237, 4059.878203653895, 4077.91844163121, 4144.585541445831, 4200.4539409876525, 1318.9142705724098, 1320.9405576748877, 1325.2247435250295, 1345.643404497809, 1350.4058214385639, 871.4945331707594, 876.4390789894472, 888.338188049907, 901.6663834381321, 991.1426615977127, 5948.702564871739, 5975.25309336333, 6022.096685497123, 6053.878922421527, 6354.650494222862, 966.7486453522135, 997.0777031246068, 1012.5342342522522, 1042.9634962776418, 1045.6444808764904, 3975.866877939773, 4085.264652362544, 4101.8974525637095, 4127.277726397869, 4169.8957526062095, 10415.843762343571, 10591.682249532527, 10943.23397362193, 11055.613053543088, 11471.827922581168, 10822.67531974042, 11147.665570032912, 11221.158413119048, 11226.158038147172, 11237.651481822233, 7867.0, 8076.232757887991, 8110.188981101961, 8115.3507719382305, 8122.390820688472, 6033.78881739137, 6140.631562106288, 6197.225346831646, 6248.0940263660605, 6389.9041514376395, 10313.9484302578, 10495.21285903545, 10566.894331056592, 10574.629887953884, 10657.0, 10836.241463097824, 10848.67453976382, 10920.126389888948, 10953.83569246446, 11006.779864402828, 7637.732679356196, 7698.923010770045, 7703.3067023968115, 7757.7672669819995, 7832.921670783448, 8639.0, 8645.783855403664, 8690.00064992534, 8697.739067827975, 8702.216800487986, 7824.413169012233, 7839.882401764092, 7853.607319634109, 7860.84278314442, 8096.352291816523, 7389.408847292311, 7842.490238134731, 8009.959950200212, 8077.798055048671, 8092.9190708092165, 7126.679299431435, 7354.852902941804, 7438.702451901863, 7442.4418168636485, 7444.255574442729, 4805.519448055166, 5162.457941916045, 5206.583473322201, 5226.163813789775, 5379.892402151857, 5009.198528235464, 5042.974785126051, 5072.365954255718, 5200.297959775382, 5245.344331958505], "value": 4741.5208116631975, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.45851781230823, 328.62372583392016, 337.44658759634206, 337.6033161035782, 338.8695352289371, 5116.232565115234, 5187.247849061884, 5229.163333866572, 5261.316028916241, 5269.288646032789, 5086.186210206359, 5188.533032027126, 5202.647311698957, 5203.661761985478, 5227.555657769085, 4098.836046558149, 4100.466939297887, 4101.3847922811665, 4102.44616976708, 4113.773742444165, 4068.7151899367054, 4124.6700263978955, 4129.112240868213, 4132.421460995463, 4142.378643203513, 4102.425660407545, 4143.0, 4144.171165766858, 4150.273702102135, 4163.084121493267, 4131.235721391552, 4134.131832315217, 4136.420901073852, 4138.441310423084, 4158.334666453383, 3704.388775852008, 3708.684761795251, 3712.832922518466, 3714.4428335749585, 3730.742900545158, 4917.59038071346, 5100.209467532502, 5124.925044972986, 5125.7441926727815, 5140.1518749406305, 4639.6752227344095, 4668.0, 4678.298255261704, 4679.251319788851, 4699.929501057487, 5444.265024221719, 5444.891102177974, 5449.591280653967, 5451.48210919962, 5458.399576046631, 3855.496356420998, 4027.7784721840285, 4039.4950631171105, 4054.331035379159, 4057.7159598828093, 5635.3237611486475, 5646.802361917355, 5646.943530564683, 5663.971680141614, 5668.404817494186, 4131.462909821704, 4133.834646614162, 4137.386419296477, 4143.70994030421, 4160.9375859362135, 4000.959990400117, 4186.3092589722355, 4190.874273771792, 4198.454200953856, 4216.367544868294, 4696.81212751493, 4698.989717210748, 4709.293605959065, 4729.810807567659, 4746.620270378361, 5495.285612870382, 5516.0, 5516.172574113915, 5518.392976772552, 5519.31008623922, 1333.9866601334056, 1353.3842101843638, 1434.2828585707146, 1446.146773403697, 1449.6883170118265, 901.5221932375852, 924.0, 937.7727896246415, 938.3853575907791, 939.4457270210609, 4622.2835460503675, 4634.698744581591, 4636.39726835516, 4644.883877903012, 4645.953540464619, 4859.88222708779, 4863.610911127102, 4895.853124406273, 4940.752962351868, 4957.652964292465, 4643.930341044887, 4690.859274221779, 4696.459907110658, 4706.117552978836, 4707.03505781314, 2805.7895657825743, 2922.956155657667, 2926.809757365764, 2958.2160727407254, 2971.450281697893, 5770.24986751728, 5772.0, 5780.971095144498, 5784.363719990798, 5793.7392817323325, 5523.309586301712, 5730.971345143331, 5739.196512488255, 5752.395998420106, 5761.164631128547, 5742.16738572913, 5744.396838331999, 5788.18965344852, 5795.9420405796245, 5815.720541480868, 5574.275344205227, 5575.553955683617, 5591.245181900392, 5592.384837667934, 5597.3003374578175, 5358.169483730028, 5527.2538207343105, 5595.524380427709, 5606.159076138611, 5610.635308704921, 5736.913946290892, 5740.1963725077685, 5742.052561327334, 5743.8564035899435, 5809.448102430262, 3880.5149356330458, 3945.1123497214044, 3959.0894094359037, 3974.642282194616, 3978.5822488639433, 5324.680519168863, 5476.397596264328, 5476.767727261335, 5508.944910551004, 5511.944880551143, 5268.6575372600655, 5337.172738225581, 5423.105187643994, 5427.3215848019, 5444.891102178013, 5412.918806217831, 5431.37539182999, 5440.564754819684, 5444.3194600674915, 5444.673319600836, 5126.53861152498, 5295.814646487354, 5326.520613144836, 5340.0, 5391.627683819107, 5478.342598888159, 5495.53287970527, 5511.972440137773, 5516.420775818481, 5533.426956465263, 5384.60769715077, 5387.084195686821, 5393.217983392465, 5394.622376433612, 5409.432009639088], "value": 4147.053634123622, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.8237940514871, 731.1445608637894, 736.694271877171, 805.66162211871, 815.8776183572467, 16631.750523742154, 17281.48155555334, 17283.999999999985, 17293.30826766931, 17322.0, 16696.49910502684, 16827.326906923707, 16839.0, 16846.83153168468, 16851.74722379165, 663.8506336074392, 804.4569915307159, 821.8356328734247, 823.8475881961826, 824.2375802382802, 671.4091599392527, 798.9880151797728, 812.191869090256, 818.8771684247351, 823.3577809308749, 845.9407841451101, 851.6125163050797, 852.1052894460822, 852.2031900173358, 855.0765173612515, 681.9659017049128, 847.0428415890067, 847.2713466418875, 847.771101802516, 847.8134810341714, 509.7935336188812, 602.220124938206, 612.6844674992383, 614.7633161232912, 623.0840664223584, 15769.448069317634, 15787.368505259776, 15840.841591584052, 15850.76223856643, 15961.36154553828, 13973.441062357493, 14127.646808829759, 14529.491467798576, 14560.271986400638, 14796.556103316918, 13271.535496257682, 13481.932590337083, 13565.457381704819, 13780.379882905294, 13953.302334883318, 4410.338449232609, 4490.550944905516, 4502.234620114588, 4525.162844873709, 4535.070310586322, 77625.61187194084, 78640.60679696564, 78974.0, 79277.0, 79408.60295698485, 10646.307989980627, 11049.17131215162, 11166.776664466664, 11192.720181995515, 11532.077433805276, 20296.59406811885, 20423.0, 20456.284030058876, 20469.385918422173, 20500.89749551243, 7626.885596716053, 7908.090569584571, 8015.7595272141925, 8203.097659257477, 8319.251267385964, 4414.779261036935, 4473.172463094338, 4580.862574122782, 4603.631709463235, 4607.493175750665, 3342.3983682937305, 3445.0870519312402, 3447.500112483676, 3448.9655103449145, 3450.3099380124077, 583.0526687594252, 593.4629160609655, 595.9702014899237, 613.588745884464, 619.127030886457, 636.723025483916, 642.4154019841922, 663.2306524431636, 667.2626747444101, 740.6000759589762, 8802.779930501789, 9206.769830754285, 9510.809783804285, 9521.333506654468, 9683.32216744821, 588.7380115848406, 600.7446835095067, 651.1307404614797, 698.9091418115612, 704.5878161275608, 4483.932741008887, 4553.203189441834, 4684.484706682262, 4784.976075119602, 4819.807207711722, 74401.0, 89878.55060724655, 119491.40254298846, 121703.0, 127516.36241818732, 67403.0, 106726.46636766767, 111730.0, 115041.4247928755, 115575.42212289052, 95238.5238073819, 95452.52273738587, 98449.50775246222, 98557.0, 99186.0, 8872.0, 8875.201231889161, 8962.058983806606, 8977.740478759688, 9271.72184834429, 51867.55390683924, 53877.342878997144, 54095.72952135214, 54890.725546372014, 55423.722881385336, 72129.0, 80420.0, 81166.59416702879, 83408.16591834003, 84150.57924710338, 11322.26405283654, 11364.374959377314, 11477.827832582685, 11666.183367164216, 11987.700307492383, 12756.681082973, 12787.61637150887, 12789.872101279241, 13113.606591802261, 13448.663783405493, 11884.702882428008, 12019.21875078117, 12238.204516706386, 12323.815142772686, 12815.16701414405, 11736.178467507192, 11938.164328496921, 12058.638240852788, 12443.066769992467, 12620.116591838483, 11721.765564688489, 12051.397430128634, 12407.441665125272, 12448.25310481374, 13000.934995324962, 8833.33749968742, 8965.910340896768, 9330.300227483101, 9393.295502837178, 9446.149846513847, 11496.770064598828, 11621.302721836379, 12034.338111403957, 12094.516219351128, 12163.939180304042], "value": 7450.612527799869, "unit": "score"}], "name": "dromaeo_css", "value": 7146.990528063355, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 110.0, 110.0, 111.0, 111.0, 111.0, 110.0, 111.0, 112.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 119.0, 160.0, 119.0, 121.0, 124.0, 124.0, 152.0, 117.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 160.0, 159.0, 160.0, 158.0, 161.0, 161.0, 163.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 107.0, 76.0, 77.0, 76.0, 76.0, 107.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 112.0, 82.0, 82.0, 83.0, 104.0, 82.0, 81.0, 110.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 48.0, 48.0, 50.0, 52.0, 51.0, 50.0, 51.0, 51.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 60.0, 60.0, 61.0, 61.0, 61.0, 60.0, 59.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 78.0, 79.0, 78.0, 78.0, 84.0, 79.0, 78.0, 79.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 126.0, 124.0, 128.0, 127.0, 139.0, 123.0, 167.0, 130.0], "value": 132.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 164.0, 165.0, 171.0, 170.0, 169.0, 164.0, 165.0, 163.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 70.0, 65.0, 66.0, 69.0, 65.0, 64.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1486.0999999999997, "alertThreshold": 2.0}]} 08:31:29 INFO - Return code: 0 08:31:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:31:29 INFO - # TBPL SUCCESS # 08:31:29 INFO - Running post-action listener: _resource_record_post_action 08:31:29 INFO - [mozharness: 2016-09-17 15:31:29.758695Z] Finished run-tests step (success) 08:31:29 INFO - Running post-run listener: _resource_record_post_run 08:31:29 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 411791360; Read time: 12; Write time: 301688 08:31:29 INFO - TinderboxPrint: CPU usage
13.1% 08:31:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:31:29 INFO - TinderboxPrint: I/O write bytes / time
411,791,360 / 301,688 08:31:29 INFO - TinderboxPrint: CPU idle
8,288.4 (86.9%) 08:31:29 INFO - TinderboxPrint: CPU user
1,193.1 (12.5%) 08:31:29 INFO - TinderboxPrint: Swap in / out
0 / 0 08:31:29 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 114155520; Read time: 0; Write time: 70900 08:31:29 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 296382464; Read time: 0; Write time: 230752 08:31:30 INFO - Running post-run listener: _upload_blobber_files 08:31:30 INFO - Blob upload gear active. 08:31:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:31:30 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 08:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:31:30 INFO - (blobuploader) - INFO - Open directory for files ... 08:31:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:31:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:31:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:31 INFO - (blobuploader) - INFO - Done attempting. 08:31:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:31:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:31:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:32 INFO - (blobuploader) - INFO - Done attempting. 08:31:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:31:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:31:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:39 INFO - (blobuploader) - INFO - Done attempting. 08:31:39 INFO - (blobuploader) - INFO - Iteration through files over. 08:31:39 INFO - Return code: 0 08:31:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:31:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:31:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b5078e3d14238b7a5adf080d4e64067d1af8da558d84d1620d8504ffae243621e87bfaff20a648b05899baa185ae3f3208d2473e1a45b913215563cd323a185", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7801975a6aad4a7654c07c0f6ffa3cc44905ecf21f0de09b2c2420b9ab43781eaf11e18fe60519afb22c05939478e7140ac76f7dc851e00cc46ff112049e3ad6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e96a6257c7b3df2441c60d2a799384fa3950eef89d6a6e0660289d50514996d6ef0be33ae4c64edd5e05056fc99d6ca9209960e2ee4bac49fe0e3f111a6384f2"} 08:31:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:31:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:31:39 INFO - Contents: 08:31:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b5078e3d14238b7a5adf080d4e64067d1af8da558d84d1620d8504ffae243621e87bfaff20a648b05899baa185ae3f3208d2473e1a45b913215563cd323a185", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7801975a6aad4a7654c07c0f6ffa3cc44905ecf21f0de09b2c2420b9ab43781eaf11e18fe60519afb22c05939478e7140ac76f7dc851e00cc46ff112049e3ad6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e96a6257c7b3df2441c60d2a799384fa3950eef89d6a6e0660289d50514996d6ef0be33ae4c64edd5e05056fc99d6ca9209960e2ee4bac49fe0e3f111a6384f2"} 08:31:39 INFO - Running post-run listener: copy_logs_to_upload_dir 08:31:39 INFO - Copying logs to upload dir... 08:31:39 INFO - mkdir: /builds/slave/test/build/upload/logs 08:31:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.736429 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-17 08:31:39.304615) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:31:39.308167) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b5078e3d14238b7a5adf080d4e64067d1af8da558d84d1620d8504ffae243621e87bfaff20a648b05899baa185ae3f3208d2473e1a45b913215563cd323a185", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7801975a6aad4a7654c07c0f6ffa3cc44905ecf21f0de09b2c2420b9ab43781eaf11e18fe60519afb22c05939478e7140ac76f7dc851e00cc46ff112049e3ad6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e96a6257c7b3df2441c60d2a799384fa3950eef89d6a6e0660289d50514996d6ef0be33ae4c64edd5e05056fc99d6ca9209960e2ee4bac49fe0e3f111a6384f2"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-linux64-pgo/1474068601/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b5078e3d14238b7a5adf080d4e64067d1af8da558d84d1620d8504ffae243621e87bfaff20a648b05899baa185ae3f3208d2473e1a45b913215563cd323a185", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7801975a6aad4a7654c07c0f6ffa3cc44905ecf21f0de09b2c2420b9ab43781eaf11e18fe60519afb22c05939478e7140ac76f7dc851e00cc46ff112049e3ad6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e96a6257c7b3df2441c60d2a799384fa3950eef89d6a6e0660289d50514996d6ef0be33ae4c64edd5e05056fc99d6ca9209960e2ee4bac49fe0e3f111a6384f2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-17 08:31:39.333551) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:31:39.333877) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1474125059.558394-1231101624 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-17 08:31:39.418046) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:31:39.418399) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-17 08:31:39.418792) ========= ========= Total master_lag: 0.17 =========