builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 starttime: 1474269717.85 results: success (0) buildid: 20160919002137 builduid: 1b06ea4f69264a619eb2b53a855a93d2 revision: c80b283448d3b1266f18f7ea0eaccb9692cc135c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:57.845924) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:57.846460) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:57.847112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:57.882766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:57.883318) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 00:21:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 113M=0s 2016-09-19 00:21:58 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126296 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:58.028006) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:58.028367) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016634 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:58.101653) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 00:21:58.102026) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c80b283448d3b1266f18f7ea0eaccb9692cc135c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c80b283448d3b1266f18f7ea0eaccb9692cc135c --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 00:21:58,315 truncating revision to first 12 chars 2016-09-19 00:21:58,316 Setting DEBUG logging. 2016-09-19 00:21:58,316 attempt 1/10 2016-09-19 00:21:58,316 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c80b283448d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 00:21:59,288 unpacking tar archive at: fx-team-c80b283448d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331519 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-19 00:21:59.453589) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:59.454279) ========= script_repo_revision: c80b283448d3b1266f18f7ea0eaccb9692cc135c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:59.455216) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:59.455872) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 00:21:59.467796) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-19 00:21:59.468097) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 00:21:59 INFO - MultiFileLogger online at 20160919 00:21:59 in /builds/slave/test 00:21:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 00:21:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:21:59 INFO - {'append_to_log': False, 00:21:59 INFO - 'base_work_dir': '/builds/slave/test', 00:21:59 INFO - 'blob_upload_branch': 'Fx-Team', 00:21:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:21:59 INFO - 'branch': 'Fx-Team', 00:21:59 INFO - 'buildbot_json_path': 'buildprops.json', 00:21:59 INFO - 'config_files': ('talos/linux_config.py',), 00:21:59 INFO - 'default_actions': ('clobber', 00:21:59 INFO - 'read-buildbot-config', 00:21:59 INFO - 'download-and-extract', 00:21:59 INFO - 'populate-webroot', 00:21:59 INFO - 'create-virtualenv', 00:21:59 INFO - 'install', 00:21:59 INFO - 'run-tests'), 00:21:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:21:59 INFO - 'download_minidump_stackwalk': True, 00:21:59 INFO - 'download_symbols': 'ondemand', 00:21:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:21:59 INFO - 'tooltool.py': '/tools/tooltool.py', 00:21:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:21:59 INFO - '/tools/misc-python/virtualenv.py')}, 00:21:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:21:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:21:59 INFO - 'installer_path': 'installer.exe', 00:21:59 INFO - 'log_level': 'info', 00:21:59 INFO - 'log_name': 'talos', 00:21:59 INFO - 'log_to_console': True, 00:21:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 00:21:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 00:21:59 INFO - 'opt_config_files': (), 00:21:59 INFO - 'pip_index': False, 00:21:59 INFO - 'sps_profile': False, 00:21:59 INFO - 'sps_profile_interval': 0, 00:21:59 INFO - 'suite': 'dromaeojs', 00:21:59 INFO - 'system_bits': '32', 00:21:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:21:59 INFO - 'title': 'talos-linux64-ix-018', 00:21:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:21:59 INFO - 'use_talos_json': True, 00:21:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:21:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:21:59 INFO - 'work_dir': 'build'} 00:21:59 INFO - [mozharness: 2016-09-19 07:21:59.775634Z] Running clobber step. 00:21:59 INFO - Running pre-action listener: _resource_record_pre_action 00:21:59 INFO - Running main action method: clobber 00:21:59 INFO - rmtree: /builds/slave/test/build 00:21:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:22:00 INFO - Running post-action listener: _resource_record_post_action 00:22:00 INFO - [mozharness: 2016-09-19 07:22:00.737221Z] Finished clobber step (success) 00:22:00 INFO - [mozharness: 2016-09-19 07:22:00.737322Z] Running read-buildbot-config step. 00:22:00 INFO - Running pre-action listener: _resource_record_pre_action 00:22:00 INFO - Running main action method: read_buildbot_config 00:22:00 INFO - Using buildbot properties: 00:22:00 INFO - { 00:22:00 INFO - "project": "", 00:22:00 INFO - "product": "firefox", 00:22:00 INFO - "script_repo_revision": "production", 00:22:00 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 00:22:00 INFO - "repository": "", 00:22:00 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 00:22:00 INFO - "buildid": "20160919002137", 00:22:00 INFO - "stage_platform": "linux64-pgo", 00:22:00 INFO - "basedir": "/builds/slave/test", 00:22:00 INFO - "buildnumber": 1071, 00:22:00 INFO - "platform": "ubuntu64_hw", 00:22:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 00:22:00 INFO - "slavebuilddir": "test", 00:22:00 INFO - "branch": "fx-team", 00:22:00 INFO - "revision": "c80b283448d3b1266f18f7ea0eaccb9692cc135c", 00:22:00 INFO - "slavename": "talos-linux64-ix-018", 00:22:00 INFO - "builduid": "1b06ea4f69264a619eb2b53a855a93d2", 00:22:00 INFO - "repo_path": "integration/fx-team" 00:22:00 INFO - } 00:22:00 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 00:22:00 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.test_packages.json. 00:22:00 INFO - Running post-action listener: _resource_record_post_action 00:22:00 INFO - [mozharness: 2016-09-19 07:22:00.739732Z] Finished read-buildbot-config step (success) 00:22:00 INFO - [mozharness: 2016-09-19 07:22:00.739821Z] Running download-and-extract step. 00:22:00 INFO - Running pre-action listener: _resource_record_pre_action 00:22:00 INFO - Running main action method: download_and_extract 00:22:00 INFO - mkdir: /builds/slave/test/build/tests 00:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:00 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 00:22:00 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/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 00:22:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/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 00:22:00 INFO - Downloaded 1361 bytes. 00:22:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 00:22:00 INFO - Using the following test package requirements: 00:22:00 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 00:22:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:22:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 00:22:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:22:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 00:22:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:22:00 INFO - u'jsshell-linux-x86_64.zip'], 00:22:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:22:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 00:22:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 00:22:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:22:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 00:22:00 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:22:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 00:22:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:22:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 00:22:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 00:22:00 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 00:22:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 00:22:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 00:22:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:22:00 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 00:22:01 INFO - Expected file size: 21936175 00:22:08 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 00:22:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 00:22:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 00:22:08 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip into memory 00:22:08 INFO - Expected file size: 21936175 00:22:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 00:22:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 00:22:13 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip into memory 00:22:13 INFO - Expected file size: 14414059 00:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:17 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:22:17 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/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 00:22:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/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 00:22:24 INFO - Downloaded 59547086 bytes. 00:22:24 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:22:24 INFO - mkdir: /builds/slave/test/properties 00:22:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:22:24 INFO - Writing to file /builds/slave/test/properties/build_url 00:22:24 INFO - Contents: 00:22:24 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 00:22:24 INFO - Running post-action listener: _resource_record_post_action 00:22:24 INFO - Running post-action listener: set_extra_try_arguments 00:22:24 INFO - [mozharness: 2016-09-19 07:22:24.648388Z] Finished download-and-extract step (success) 00:22:24 INFO - [mozharness: 2016-09-19 07:22:24.648618Z] Running populate-webroot step. 00:22:24 INFO - Running pre-action listener: _resource_record_pre_action 00:22:24 INFO - Running main action method: populate_webroot 00:22:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:22:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:22:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:22:24 INFO - '--tppagecycles', 00:22:24 INFO - '7'], 00:22:24 INFO - 'tests': ['tsvgm']}}, 00:22:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:22:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:22:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:22:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:22:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:22:24 INFO - 'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:22:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:22:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:22:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:22:24 INFO - 'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['damp', 'tps']}, 00:22:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:22:24 INFO - 'tests': ['damp', 'tps']}, 00:22:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['dromaeo_dom']}, 00:22:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:22:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['basic_compositor_video']}, 00:22:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:22:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['a11yr', 00:22:24 INFO - 'ts_paint', 00:22:24 INFO - 'tpaint', 00:22:24 INFO - 'sessionrestore', 00:22:24 INFO - 'sessionrestore_no_auto_restore', 00:22:24 INFO - 'tabpaint']}, 00:22:24 INFO - 'other-e10s': {'tests': ['a11yr', 00:22:24 INFO - 'ts_paint', 00:22:24 INFO - 'tpaint', 00:22:24 INFO - 'sessionrestore', 00:22:24 INFO - 'sessionrestore_no_auto_restore', 00:22:24 INFO - 'tabpaint']}, 00:22:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:22:24 INFO - 'ts_paint', 00:22:24 INFO - 'tpaint', 00:22:24 INFO - 'sessionrestore', 00:22:24 INFO - 'sessionrestore_no_auto_restore', 00:22:24 INFO - 'tabpaint']}, 00:22:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:22:24 INFO - 'ts_paint', 00:22:24 INFO - 'tpaint', 00:22:24 INFO - 'sessionrestore', 00:22:24 INFO - 'sessionrestore_no_auto_restore', 00:22:24 INFO - 'tabpaint']}, 00:22:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['a11yr', 00:22:24 INFO - 'ts_paint', 00:22:24 INFO - 'tpaint', 00:22:24 INFO - 'sessionrestore', 00:22:24 INFO - 'sessionrestore_no_auto_restore', 00:22:24 INFO - 'tabpaint']}, 00:22:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['a11yr', 00:22:24 INFO - 'ts_paint', 00:22:24 INFO - 'tpaint', 00:22:24 INFO - 'sessionrestore', 00:22:24 INFO - 'sessionrestore_no_auto_restore', 00:22:24 INFO - 'tabpaint']}, 00:22:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['tsvgx', 00:22:24 INFO - 'tsvgr_opacity', 00:22:24 INFO - 'tart', 00:22:24 INFO - 'tscrollx', 00:22:24 INFO - 'cart']}, 00:22:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:22:24 INFO - 'tsvgr_opacity', 00:22:24 INFO - 'tart', 00:22:24 INFO - 'tscrollx', 00:22:24 INFO - 'cart']}, 00:22:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:22:24 INFO - 'talos_options': ['--disable-e10s'], 00:22:24 INFO - 'tests': ['tp5o']}, 00:22:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:22:24 INFO - 'tests': ['tp5o']}, 00:22:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:22:24 INFO - 'talos_options': ['--disable-e10s', 00:22:24 INFO - '--xperf_path', 00:22:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:22:24 INFO - 'tests': ['tp5n']}, 00:22:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:22:24 INFO - 'talos_options': ['--xperf_path', 00:22:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:22:24 INFO - 'tests': ['tp5n']}}, 00:22:24 INFO - 'talos.zip': {'path': '', 00:22:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:22:24 INFO - Running post-action listener: _resource_record_post_action 00:22:24 INFO - [mozharness: 2016-09-19 07:22:24.670201Z] Finished populate-webroot step (success) 00:22:24 INFO - [mozharness: 2016-09-19 07:22:24.670291Z] Running create-virtualenv step. 00:22:24 INFO - Running pre-action listener: _resource_record_pre_action 00:22:24 INFO - Running main action method: create_virtualenv 00:22:24 INFO - Creating virtualenv /builds/slave/test/build/venv 00:22:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 00:22:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:22:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:22:24 INFO - Using real prefix '/usr' 00:22:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:22:25 INFO - Installing distribute.............................................................................................................................................................................................done. 00:22:27 INFO - Installing pip.................done. 00:22:27 INFO - Return code: 0 00:22:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:27 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 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:22:27 INFO - Using env: {'DISPLAY': ':0', 00:22:27 INFO - 'HOME': '/home/cltbld', 00:22:27 INFO - 'LANG': 'en_US.UTF-8', 00:22:27 INFO - 'LANGUAGE': 'en_US:en', 00:22:27 INFO - 'LOGNAME': 'cltbld', 00:22:27 INFO - 'MAIL': '/var/mail/cltbld', 00:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:27 INFO - 'MOZ_NO_REMOTE': '1', 00:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:27 INFO - 'NO_EM_RESTART': '1', 00:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:27 INFO - 'PWD': '/builds/slave/test', 00:22:27 INFO - 'SHELL': '/bin/bash', 00:22:27 INFO - 'SHLVL': '1', 00:22:27 INFO - 'TERM': 'linux', 00:22:27 INFO - 'TMOUT': '86400', 00:22:27 INFO - 'USER': 'cltbld', 00:22:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:27 INFO - '_': '/tools/buildbot/bin/python'} 00:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 00:22:27 INFO - Cleaning up... 00:22:27 INFO - Return code: 0 00:22:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:27 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 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:22:27 INFO - Using env: {'DISPLAY': ':0', 00:22:27 INFO - 'HOME': '/home/cltbld', 00:22:27 INFO - 'LANG': 'en_US.UTF-8', 00:22:27 INFO - 'LANGUAGE': 'en_US:en', 00:22:27 INFO - 'LOGNAME': 'cltbld', 00:22:27 INFO - 'MAIL': '/var/mail/cltbld', 00:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:27 INFO - 'MOZ_NO_REMOTE': '1', 00:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:27 INFO - 'NO_EM_RESTART': '1', 00:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:27 INFO - 'PWD': '/builds/slave/test', 00:22:27 INFO - 'SHELL': '/bin/bash', 00:22:27 INFO - 'SHLVL': '1', 00:22:27 INFO - 'TERM': 'linux', 00:22:27 INFO - 'TMOUT': '86400', 00:22:27 INFO - 'USER': 'cltbld', 00:22:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:27 INFO - '_': '/tools/buildbot/bin/python'} 00:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:27 INFO - Downloading/unpacking psutil>=3.1.1 00:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:22:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:22:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:22:29 INFO - Installing collected packages: psutil 00:22:29 INFO - Running setup.py install for psutil 00:22:29 INFO - building 'psutil._psutil_linux' extension 00:22:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 00:22:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 00:22:29 INFO - building 'psutil._psutil_posix' extension 00:22:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 00:22:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 00:22:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:22:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:22:30 INFO - Successfully installed psutil 00:22:30 INFO - Cleaning up... 00:22:30 INFO - Return code: 0 00:22:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:22: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')]} 00:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22: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')]} 00:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 00:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 00:22:30 INFO - Using env: {'DISPLAY': ':0', 00:22:30 INFO - 'HOME': '/home/cltbld', 00:22:30 INFO - 'LANG': 'en_US.UTF-8', 00:22:30 INFO - 'LANGUAGE': 'en_US:en', 00:22:30 INFO - 'LOGNAME': 'cltbld', 00:22:30 INFO - 'MAIL': '/var/mail/cltbld', 00:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:30 INFO - 'MOZ_NO_REMOTE': '1', 00:22:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:30 INFO - 'NO_EM_RESTART': '1', 00:22:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:30 INFO - 'PWD': '/builds/slave/test', 00:22:30 INFO - 'SHELL': '/bin/bash', 00:22:30 INFO - 'SHLVL': '1', 00:22:30 INFO - 'TERM': 'linux', 00:22:30 INFO - 'TMOUT': '86400', 00:22:30 INFO - 'USER': 'cltbld', 00:22:30 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:30 INFO - '_': '/tools/buildbot/bin/python'} 00:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:22:32 INFO - Installing collected packages: mozsystemmonitor 00:22:32 INFO - Running setup.py install for mozsystemmonitor 00:22:32 INFO - Successfully installed mozsystemmonitor 00:22:32 INFO - Cleaning up... 00:22:32 INFO - Return code: 0 00:22:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 00:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 00:22:32 INFO - Using env: {'DISPLAY': ':0', 00:22:32 INFO - 'HOME': '/home/cltbld', 00:22:32 INFO - 'LANG': 'en_US.UTF-8', 00:22:32 INFO - 'LANGUAGE': 'en_US:en', 00:22:32 INFO - 'LOGNAME': 'cltbld', 00:22:32 INFO - 'MAIL': '/var/mail/cltbld', 00:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:32 INFO - 'MOZ_NO_REMOTE': '1', 00:22:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:32 INFO - 'NO_EM_RESTART': '1', 00:22:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:32 INFO - 'PWD': '/builds/slave/test', 00:22:32 INFO - 'SHELL': '/bin/bash', 00:22:32 INFO - 'SHLVL': '1', 00:22:32 INFO - 'TERM': 'linux', 00:22:32 INFO - 'TMOUT': '86400', 00:22:32 INFO - 'USER': 'cltbld', 00:22:32 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:32 INFO - '_': '/tools/buildbot/bin/python'} 00:22:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:32 INFO - Downloading/unpacking jsonschema==2.5.1 00:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:22:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:35 INFO - Downloading functools32-3.2.3-2.tar.gz 00:22:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:22:35 INFO - warning: no files found matching '*.txt' 00:22:35 INFO - no previously-included directories found matching 'build' 00:22:35 INFO - no previously-included directories found matching 'dist' 00:22:35 INFO - no previously-included directories found matching '.git*' 00:22:35 INFO - Installing collected packages: jsonschema, functools32 00:22:35 INFO - Running setup.py install for functools32 00:22:35 INFO - warning: no files found matching '*.txt' 00:22:35 INFO - no previously-included directories found matching 'build' 00:22:35 INFO - no previously-included directories found matching 'dist' 00:22:35 INFO - no previously-included directories found matching '.git*' 00:22:35 INFO - Successfully installed jsonschema functools32 00:22:35 INFO - Cleaning up... 00:22:35 INFO - Return code: 0 00:22:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 00:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 00:22:35 INFO - Using env: {'DISPLAY': ':0', 00:22:35 INFO - 'HOME': '/home/cltbld', 00:22:35 INFO - 'LANG': 'en_US.UTF-8', 00:22:35 INFO - 'LANGUAGE': 'en_US:en', 00:22:35 INFO - 'LOGNAME': 'cltbld', 00:22:35 INFO - 'MAIL': '/var/mail/cltbld', 00:22:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:35 INFO - 'MOZ_NO_REMOTE': '1', 00:22:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:35 INFO - 'NO_EM_RESTART': '1', 00:22:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:35 INFO - 'PWD': '/builds/slave/test', 00:22:35 INFO - 'SHELL': '/bin/bash', 00:22:35 INFO - 'SHLVL': '1', 00:22:35 INFO - 'TERM': 'linux', 00:22:35 INFO - 'TMOUT': '86400', 00:22:35 INFO - 'USER': 'cltbld', 00:22:35 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:35 INFO - '_': '/tools/buildbot/bin/python'} 00:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:22:35 INFO - Cleaning up... 00:22:35 INFO - Return code: 0 00:22:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:22:35 INFO - Using env: {'DISPLAY': ':0', 00:22:35 INFO - 'HOME': '/home/cltbld', 00:22:35 INFO - 'LANG': 'en_US.UTF-8', 00:22:35 INFO - 'LANGUAGE': 'en_US:en', 00:22:35 INFO - 'LOGNAME': 'cltbld', 00:22:35 INFO - 'MAIL': '/var/mail/cltbld', 00:22:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:35 INFO - 'MOZ_NO_REMOTE': '1', 00:22:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:35 INFO - 'NO_EM_RESTART': '1', 00:22:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:35 INFO - 'PWD': '/builds/slave/test', 00:22:35 INFO - 'SHELL': '/bin/bash', 00:22:35 INFO - 'SHLVL': '1', 00:22:35 INFO - 'TERM': 'linux', 00:22:35 INFO - 'TMOUT': '86400', 00:22:35 INFO - 'USER': 'cltbld', 00:22:35 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:35 INFO - '_': '/tools/buildbot/bin/python'} 00:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:35 INFO - Downloading/unpacking blobuploader==1.2.4 00:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:37 INFO - Downloading blobuploader-1.2.4.tar.gz 00:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:22:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:22:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:38 INFO - Downloading docopt-0.6.1.tar.gz 00:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:22:38 INFO - Installing collected packages: blobuploader, requests, docopt 00:22:38 INFO - Running setup.py install for blobuploader 00:22:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:22:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:22:38 INFO - Running setup.py install for requests 00:22:38 INFO - Running setup.py install for docopt 00:22:38 INFO - Successfully installed blobuploader requests docopt 00:22:38 INFO - Cleaning up... 00:22:39 INFO - Return code: 0 00:22:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:39 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 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 00:22:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:22:39 INFO - Using env: {'DISPLAY': ':0', 00:22:39 INFO - 'HOME': '/home/cltbld', 00:22:39 INFO - 'LANG': 'en_US.UTF-8', 00:22:39 INFO - 'LANGUAGE': 'en_US:en', 00:22:39 INFO - 'LOGNAME': 'cltbld', 00:22:39 INFO - 'MAIL': '/var/mail/cltbld', 00:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:39 INFO - 'MOZ_NO_REMOTE': '1', 00:22:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:39 INFO - 'NO_EM_RESTART': '1', 00:22:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:39 INFO - 'PWD': '/builds/slave/test', 00:22:39 INFO - 'SHELL': '/bin/bash', 00:22:39 INFO - 'SHLVL': '1', 00:22:39 INFO - 'TERM': 'linux', 00:22:39 INFO - 'TMOUT': '86400', 00:22:39 INFO - 'USER': 'cltbld', 00:22:39 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:39 INFO - '_': '/tools/buildbot/bin/python'} 00:22:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:22:39 INFO - Running setup.py (path:/tmp/pip-hXZEl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:22:39 INFO - Running setup.py (path:/tmp/pip-0vbuJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:22:39 INFO - Running setup.py (path:/tmp/pip-ba4tMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:22:39 INFO - Running setup.py (path:/tmp/pip-012Zcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:22:39 INFO - Running setup.py (path:/tmp/pip-ITEE0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:22:39 INFO - Running setup.py (path:/tmp/pip-taPpdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:22:39 INFO - Running setup.py (path:/tmp/pip-ZI5hV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:22:39 INFO - Running setup.py (path:/tmp/pip-PGzBan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:22:39 INFO - Running setup.py (path:/tmp/pip-JoEjlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:22:39 INFO - Running setup.py (path:/tmp/pip-03zsh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:22:39 INFO - Running setup.py (path:/tmp/pip-gxX4fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:22:40 INFO - Running setup.py (path:/tmp/pip-Y3xWiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:22:40 INFO - Running setup.py (path:/tmp/pip-JnoypW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:22:40 INFO - Running setup.py (path:/tmp/pip-WuZaN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:22:40 INFO - Running setup.py (path:/tmp/pip-3XpM5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:22:40 INFO - Running setup.py (path:/tmp/pip-Je6Dyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:22:40 INFO - Running setup.py (path:/tmp/pip-u0tT7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:22:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:22:40 INFO - Running setup.py install for manifestparser 00:22:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:22:40 INFO - Running setup.py install for mozcrash 00:22:40 INFO - Running setup.py install for mozdebug 00:22:40 INFO - Running setup.py install for mozdevice 00:22:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:22:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:22:40 INFO - Running setup.py install for mozfile 00:22:40 INFO - Running setup.py install for mozhttpd 00:22:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Running setup.py install for mozinfo 00:22:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Running setup.py install for mozInstall 00:22:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Running setup.py install for mozleak 00:22:41 INFO - Running setup.py install for mozlog 00:22:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Running setup.py install for moznetwork 00:22:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Running setup.py install for mozprocess 00:22:41 INFO - Running setup.py install for mozprofile 00:22:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Running setup.py install for mozrunner 00:22:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:22:41 INFO - Running setup.py install for mozscreenshot 00:22:42 INFO - Running setup.py install for moztest 00:22:42 INFO - Running setup.py install for mozversion 00:22:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:22:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:22:42 INFO - Cleaning up... 00:22:42 INFO - Return code: 0 00:22:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:42 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 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 00:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:22:42 INFO - Using env: {'DISPLAY': ':0', 00:22:42 INFO - 'HOME': '/home/cltbld', 00:22:42 INFO - 'LANG': 'en_US.UTF-8', 00:22:42 INFO - 'LANGUAGE': 'en_US:en', 00:22:42 INFO - 'LOGNAME': 'cltbld', 00:22:42 INFO - 'MAIL': '/var/mail/cltbld', 00:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:42 INFO - 'MOZ_NO_REMOTE': '1', 00:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:42 INFO - 'NO_EM_RESTART': '1', 00:22:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:42 INFO - 'PWD': '/builds/slave/test', 00:22:42 INFO - 'SHELL': '/bin/bash', 00:22:42 INFO - 'SHLVL': '1', 00:22:42 INFO - 'TERM': 'linux', 00:22:42 INFO - 'TMOUT': '86400', 00:22:42 INFO - 'USER': 'cltbld', 00:22:42 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:42 INFO - '_': '/tools/buildbot/bin/python'} 00:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:22:42 INFO - Running setup.py (path:/tmp/pip-O0JT73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 00:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:22:42 INFO - Running setup.py (path:/tmp/pip-dYb_8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:22:42 INFO - Running setup.py (path:/tmp/pip-NZYlWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 00:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:22:42 INFO - Running setup.py (path:/tmp/pip-OFvoXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:22:42 INFO - Running setup.py (path:/tmp/pip-N9yw2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 00:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:22:42 INFO - Running setup.py (path:/tmp/pip-_useTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 00:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:22:42 INFO - Running setup.py (path:/tmp/pip-gpYMl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 00:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:22:43 INFO - Running setup.py (path:/tmp/pip-HSxmEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:22:43 INFO - Running setup.py (path:/tmp/pip-QB1McT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:22:43 INFO - Running setup.py (path:/tmp/pip-lxXlRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:22:43 INFO - Running setup.py (path:/tmp/pip-n0CXrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:22:43 INFO - Running setup.py (path:/tmp/pip-SA2NR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:22:43 INFO - Running setup.py (path:/tmp/pip-vAVzls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:22:43 INFO - Running setup.py (path:/tmp/pip-Eb8Q7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:22:43 INFO - Running setup.py (path:/tmp/pip-fbPOhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:22:43 INFO - Running setup.py (path:/tmp/pip-xly9Q_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 00:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:22:43 INFO - Running setup.py (path:/tmp/pip-3Unytz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 00:22:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:22:45 INFO - Downloading blessings-1.6.tar.gz 00:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:22:45 INFO - Installing collected packages: blessings 00:22:45 INFO - Running setup.py install for blessings 00:22:45 INFO - Successfully installed blessings 00:22:45 INFO - Cleaning up... 00:22:45 INFO - Return code: 0 00:22:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:22:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:22:46 INFO - Reading from file tmpfile_stdout 00:22:46 INFO - Current package versions: 00:22:46 INFO - argparse == 1.2.1 00:22:46 INFO - blessings == 1.6 00:22:46 INFO - blobuploader == 1.2.4 00:22:46 INFO - docopt == 0.6.1 00:22:46 INFO - functools32 == 3.2.3-2 00:22:46 INFO - jsonschema == 2.5.1 00:22:46 INFO - manifestparser == 1.1 00:22:46 INFO - mozInstall == 1.12 00:22:46 INFO - mozcrash == 0.17 00:22:46 INFO - mozdebug == 0.1 00:22:46 INFO - mozdevice == 0.48 00:22:46 INFO - mozfile == 1.2 00:22:46 INFO - mozhttpd == 0.7 00:22:46 INFO - mozinfo == 0.9 00:22:46 INFO - mozleak == 0.1 00:22:46 INFO - mozlog == 3.4 00:22:46 INFO - moznetwork == 0.27 00:22:46 INFO - mozprocess == 0.23 00:22:46 INFO - mozprofile == 0.28 00:22:46 INFO - mozrunner == 6.12 00:22:46 INFO - mozscreenshot == 0.1 00:22:46 INFO - mozsystemmonitor == 0.3 00:22:46 INFO - moztest == 0.7 00:22:46 INFO - mozversion == 1.4 00:22:46 INFO - psutil == 3.1.1 00:22:46 INFO - requests == 1.2.3 00:22:46 INFO - wsgiref == 0.1.2 00:22:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:46 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 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 00:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 00:22:46 INFO - Using env: {'DISPLAY': ':0', 00:22:46 INFO - 'HOME': '/home/cltbld', 00:22:46 INFO - 'LANG': 'en_US.UTF-8', 00:22:46 INFO - 'LANGUAGE': 'en_US:en', 00:22:46 INFO - 'LOGNAME': 'cltbld', 00:22:46 INFO - 'MAIL': '/var/mail/cltbld', 00:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:46 INFO - 'MOZ_NO_REMOTE': '1', 00:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:46 INFO - 'NO_EM_RESTART': '1', 00:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:46 INFO - 'PWD': '/builds/slave/test', 00:22:46 INFO - 'SHELL': '/bin/bash', 00:22:46 INFO - 'SHLVL': '1', 00:22:46 INFO - 'TERM': 'linux', 00:22:46 INFO - 'TMOUT': '86400', 00:22:46 INFO - 'USER': 'cltbld', 00:22:46 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:46 INFO - '_': '/tools/buildbot/bin/python'} 00:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 00:22:46 INFO - Cleaning up... 00:22:46 INFO - Return code: 0 00:22:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:22:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f23897acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17768a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1843790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 00:22:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:22:46 INFO - Using env: {'DISPLAY': ':0', 00:22:46 INFO - 'HOME': '/home/cltbld', 00:22:46 INFO - 'LANG': 'en_US.UTF-8', 00:22:46 INFO - 'LANGUAGE': 'en_US:en', 00:22:46 INFO - 'LOGNAME': 'cltbld', 00:22:46 INFO - 'MAIL': '/var/mail/cltbld', 00:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:22:46 INFO - 'MOZ_NO_REMOTE': '1', 00:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:22:46 INFO - 'NO_EM_RESTART': '1', 00:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:22:46 INFO - 'PWD': '/builds/slave/test', 00:22:46 INFO - 'SHELL': '/bin/bash', 00:22:46 INFO - 'SHLVL': '1', 00:22:46 INFO - 'TERM': 'linux', 00:22:46 INFO - 'TMOUT': '86400', 00:22:46 INFO - 'USER': 'cltbld', 00:22:46 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:22:46 INFO - '_': '/tools/buildbot/bin/python'} 00:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:22:46 INFO - Cleaning up... 00:22:46 INFO - Return code: 0 00:22:46 INFO - Running post-action listener: _resource_record_post_action 00:22:46 INFO - Running post-action listener: _start_resource_monitoring 00:22:46 INFO - Starting resource monitoring. 00:22:46 INFO - [mozharness: 2016-09-19 07:22:46.577020Z] Finished create-virtualenv step (success) 00:22:46 INFO - [mozharness: 2016-09-19 07:22:46.577572Z] Running install step. 00:22:46 INFO - Running pre-action listener: _resource_record_pre_action 00:22:46 INFO - Running main action method: install 00:22:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:22:46 INFO - Reading from file tmpfile_stdout 00:22:46 INFO - Detecting whether we're running mozinstall >=1.0... 00:22:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:22:46 INFO - Reading from file tmpfile_stdout 00:22:46 INFO - Output received: 00:22:46 INFO - Usage: mozinstall [options] installer 00:22:46 INFO - Options: 00:22:46 INFO - -h, --help show this help message and exit 00:22:46 INFO - -d DEST, --destination=DEST 00:22:46 INFO - Directory to install application into. [default: 00:22:46 INFO - "/builds/slave/test"] 00:22:46 INFO - --app=APP Application being installed. [default: firefox] 00:22:46 INFO - mkdir: /builds/slave/test/build/application 00:22:46 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'] 00:22:46 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 00:23:03 INFO - Reading from file tmpfile_stdout 00:23:03 INFO - Output received: 00:23:03 INFO - /builds/slave/test/build/application/firefox/firefox 00:23:03 INFO - Running post-action listener: _resource_record_post_action 00:23:03 INFO - [mozharness: 2016-09-19 07:23:03.788984Z] Finished install step (success) 00:23:03 INFO - [mozharness: 2016-09-19 07:23:03.789231Z] Running run-tests step. 00:23:03 INFO - Running pre-action listener: _resource_record_pre_action 00:23:03 INFO - Running main action method: run_tests 00:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:23:03 INFO - Python 2.7.3 00:23:03 INFO - Return code: 0 00:23:03 INFO - grabbing minidump binary from tooltool 00:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:23:03 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 0x1840930>, '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 0x1841830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1841cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:23:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 00:23:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 00:23:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 00:23:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:23:03 INFO - Return code: 0 00:23:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 00:23:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:23:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:23:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 00:23:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 00:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 00:23:03 INFO - Using env: {'DISPLAY': ':0', 00:23:03 INFO - 'HOME': '/home/cltbld', 00:23:03 INFO - 'LANG': 'en_US.UTF-8', 00:23:03 INFO - 'LANGUAGE': 'en_US:en', 00:23:03 INFO - 'LOGNAME': 'cltbld', 00:23:03 INFO - 'MAIL': '/var/mail/cltbld', 00:23:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:23:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 00:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:23:03 INFO - 'MOZ_NO_REMOTE': '1', 00:23:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 00:23:03 INFO - 'NO_EM_RESTART': '1', 00:23:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 00:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:23:03 INFO - 'PWD': '/builds/slave/test', 00:23:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:23:03 INFO - 'SHELL': '/bin/bash', 00:23:03 INFO - 'SHLVL': '1', 00:23:03 INFO - 'TERM': 'linux', 00:23:03 INFO - 'TMOUT': '86400', 00:23:03 INFO - 'USER': 'cltbld', 00:23:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397', 00:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:23:03 INFO - '_': '/tools/buildbot/bin/python'} 00:23:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 00:23:04 INFO - mozversion application_buildid: 20160918213025 00:23:04 INFO - mozversion application_changeset: c80b283448d3b1266f18f7ea0eaccb9692cc135c 00:23:04 INFO - mozversion application_display_name: Nightly 00:23:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:23:04 INFO - mozversion application_name: Firefox 00:23:04 INFO - mozversion application_remotingname: firefox 00:23:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 00:23:04 INFO - mozversion application_vendor: Mozilla 00:23:04 INFO - mozversion application_version: 51.0a1 00:23:04 INFO - mozversion platform_buildid: 20160918213025 00:23:04 INFO - mozversion platform_changeset: c80b283448d3b1266f18f7ea0eaccb9692cc135c 00:23:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 00:23:04 INFO - mozversion platform_version: 51.0a1 00:23:04 INFO - using testdate: 1474269784 00:23:04 INFO - actual date: 1474269784 00:23:04 INFO - starting webserver on 'localhost:51320' 00:23:04 INFO - SUITE-START | Running 2 tests 00:23:04 INFO - TEST-START | dromaeo_css 00:23:04 INFO - operating with platform_type : linux_ 00:23:04 INFO - Initialising browser for dromaeo_css test... 00:23:04 INFO - TEST-INFO | started process 16678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj1rVlV/profile http://localhost:51320/getInfo.html) 00:23:08 INFO - PROCESS | 16678 | __metrics Screen width/height:1600/1200 00:23:08 INFO - PROCESS | 16678 | colorDepth:24 00:23:08 INFO - PROCESS | 16678 | Browser inner width/height: 1024/697 00:23:08 INFO - PROCESS | 16678 | __metrics 00:23:08 INFO - PROCESS | 16678 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:23:08 INFO - PROCESS | 16678 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:23:09 INFO - PROCESS | 16678 | console.error: PushService: 00:23:09 INFO - PROCESS | 16678 | stateChangeProcessEnqueue: Error transitioning state 00:23:09 INFO - PROCESS | 16678 | UnknownError 00:23:09 INFO - TEST-INFO | 16678: exit 0 00:23:09 INFO - Browser initialized. 00:23:09 INFO - Running cycle 1/1 for dromaeo_css test... 00:23:09 INFO - TEST-INFO | started process 16761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj1rVlV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:23:21 INFO - PROCESS | 16761 | RSS: Main: 172806144 00:23:21 INFO - PROCESS | 16761 | 00:23:26 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:23:31 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:23:32 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 2.01673 seconds. 00:24:04 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:24:06 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:24:11 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.16672 seconds. 00:24:13 INFO - PROCESS | 16761 | 1474269853341 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 00:24:16 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:24:19 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:24:22 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.0001 seconds. 00:24:32 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 00:24:38 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01658 seconds. 00:24:40 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:24:42 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:24:49 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 00:24:57 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:25:01 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.06671 seconds. 00:25:06 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.05004 seconds. 00:25:13 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 00:25:15 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:25:31 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01679 seconds. 00:25:41 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 00:25:44 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 00:26:04 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:26:21 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:26:22 INFO - PROCESS | 16761 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51320/tests/dromaeo/cssquery-ext.html) 00:26:24 INFO - PROCESS | 16761 | RSS: Main: 321441792 00:26:24 INFO - PROCESS | 16761 | 00:26:27 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.08341 seconds. 00:27:13 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01672 seconds. 00:27:43 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:27:55 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:28:09 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:28:11 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:28:19 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:28:25 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 00:28:33 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01681 seconds. 00:28:39 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:28:41 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:29:02 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:29:12 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:29:26 INFO - PROCESS | 16761 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-ext.html (next: http://localhost:51320/tests/dromaeo/cssquery-jquery.html) 00:29:27 INFO - PROCESS | 16761 | RSS: Main: 271097856 00:29:27 INFO - PROCESS | 16761 | 00:29:33 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.03336 seconds. 00:29:51 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:30:19 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:30:46 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:30:52 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:31:08 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:31:15 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:31:20 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:31:46 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:31:48 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:32:06 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:32:22 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:32:29 INFO - PROCESS | 16761 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51320/tests/dromaeo/cssquery-mootools.html) 00:32:29 INFO - PROCESS | 16761 | RSS: Main: 214007808 00:32:29 INFO - PROCESS | 16761 | 00:32:33 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:32:35 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.03339 seconds. 00:32:44 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:32:46 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00001 seconds. 00:33:00 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:33:16 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:33:44 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:33:52 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01669 seconds. 00:34:11 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:34:23 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:34:33 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:34:43 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:34:45 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:34:55 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:35:00 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:35:05 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:35:12 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:35:21 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:35:23 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:35:31 INFO - PROCESS | 16761 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51320/tests/dromaeo/cssquery-prototype.html) 00:35:32 INFO - PROCESS | 16761 | RSS: Main: 255488000 00:35:32 INFO - PROCESS | 16761 | 00:35:35 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.03341 seconds. 00:35:40 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:35:45 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.05008 seconds. 00:35:47 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:35:58 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:36:01 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 00:36:10 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:36:19 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:36:51 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:37:03 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01664 seconds. 00:37:16 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:37:26 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.03337 seconds. 00:37:36 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:37:38 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:37:48 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:37:50 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:37:56 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:38:10 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00006 seconds. 00:38:16 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:38:22 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:38:28 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:38:34 INFO - PROCESS | 16761 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51320/tests/dromaeo/cssquery-yui.html) 00:38:35 INFO - PROCESS | 16761 | RSS: Main: 220430336 00:38:35 INFO - PROCESS | 16761 | 00:38:38 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.03338 seconds. 00:38:42 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:38:50 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01668 seconds. 00:38:53 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:39:00 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:39:16 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:39:34 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00005 seconds. 00:39:49 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.01671 seconds. 00:40:04 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.0167 seconds. 00:40:33 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1 seconds. 00:40:45 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00004 seconds. 00:40:55 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00002 seconds. 00:41:01 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:41:03 INFO - PROCESS | 16761 | [GFX1-]: Refresh driver waiting for the compositor for 1.00003 seconds. 00:41:38 INFO - PROCESS | 16761 | Cycle 1(1): loaded http://localhost:51320/tests/dromaeo/cssquery-yui.html (next: http://localhost:51320/tests/dromaeo/cssquery-dojo.html) 00:41:38 INFO - PROCESS | 16761 | RSS: Main: 298958848 00:41:38 INFO - PROCESS | 16761 | 00:41:38 INFO - PROCESS | 16761 | __start_tp_report 00:41:38 INFO - PROCESS | 16761 | _x_x_mozilla_page_load 00:41:38 INFO - PROCESS | 16761 | _x_x_mozilla_page_load_details 00:41:38 INFO - PROCESS | 16761 | |i|pagename|runs| 00:41:38 INFO - PROCESS | 16761 | |0;dojo.html;3016.48719717648;3072.677368876268;3082.2448500117466;3106.0526539405473;3126.937461250775;5244.921326180101;5281.735913204334;5394.109971854644;5477.726113694319;5517.448255174486;5377.4084850666395;5439.211314359415;5443.428440013801;5494.038543254935;5512.4211957744465;4812.614990800727;4860.3195552622665;4894.926576101361;4905.337779399771;4979.427365852919;4950.603951683875;4996.525330093654;5003.47463516331;5011.699298042129;5013.623978201631;4813.446453657839;4838.830640927551;4849.296851956482;4850.975745121252;4962.206047032476;4879.8780030499165;4950.257461380786;4987.62592805541;4988.501149885019;4994;4836.758162091881;4865.5134486551415;4875.195592727196;4883.584895283905;4934.506549345073;5271.209318602202;5400.324959380077;5406.8918621627345;5413.458654134594;5416.566674666056;5662.292213473316;5698.43015698431;5766;5836.708164591796;5855.824325270206;5872.500837428824;5889.469947704727;5898.115282707586;5910.468057874769;5943.643381397087;5451.127819548853;5456.127019676912;5458.1266997280245;5461.098918678414;5464.808731694372;5785.595008349459;5789.247397838339;5932.614380065282;6005.909911351333;6013.338532761392;5755.482006619341;5786.247787787561;5794.82615521535;5801.477866991907;5848.912266316009;5860.941390586039;5876.676782776903;5883.558733095035;5885.293764748258;5887.35239123696;5497.505224529811;5531.834044978657;5686.459786320293;5738.454846789548;5841.532677385799;5768.711564421762;5774.162746401748;5804.767809287666;5827.446392592697;5831.154482599999;4444.933326000112;4451.221036318631;4456.019675671347;4456.041950980555;4463.330500424897;3935.0752573145287;3954.940675889864;3959.366501359826;3964.6035396460125;4038.4749982502085;5485.01269771445;5508.834734957958;5520.227168196456;5555.30558680165;5570.860728481739;5817.883642327214;5859.091840764689;5869.090291004901;5871.853203669943;5877.647341159544;5326.121190003606;5376.032314183484;5500.614956952975;5513.062779327524;5513.751881165357;4933.605311575066;4985.501449854985;4988.6757360771435;4990.251462280687;5051.393832740021;5994.820155395345;5997.820065398045;5997.850053748692;5999.970000149971;6005.789797357072;5545.085060965057;5819.5635327351465;5821.38875418075;5822.505087067478;5825.475707186373;5711.228984087095;5788.797392091256;5941.465268125889;5966;6055.333913269449;5649.152627105967;5678.971605142113;5712.857178570569;5714.685692286964;5724.771009159754;5770.221020162225;5800.39095894925;5803.448672376117;5809.564282678901;5816.098504731774;5451.809186678447;5481.917771233514;5485.369182543899;5518.475744804237;5528.585356098389;5514.972425137848;5515.61390702663;5515.779368825202;5530.640508366943;5571.554275657856;5474.5894057945015;5474.589405794661;5521.503064724194;5526.944730552804;5588.077967135378;5515.531179849758;5578.77684892616;5590.329160500766;5621.803236886853;5623.775048998158;5481.808136715196;5527.695976721319;5533.474319939599;5592.86017849557;5594.38461769197 00:41:38 INFO - PROCESS | 16761 | |1;ext.html;562.4644238839952;584.9590528662994;598.8353202869213;603.7735849056604;603.891299566078;31638;42958;44117.33823992635;44228.11543769127;44239.451619193336;45084.77457612707;45446;45554.772226138826;45574.54425455745;45663.31505027427;8932.955335223314;9095.045020272875;9100.362974591748;9101.453912765255;9105.135012173832;8949.64201431942;9012.41419307743;9040.547972601376;9040.547972601376;9046.3667543272;9049.049849765779;9102.13529714676;9111.088891110901;9113.635454581809;9114.77213069672;9041.3671043027;9070.54647267634;9073.59168837404;9082.228010619105;9082.77293067672;8916.910830891673;8926.196642302224;8976;8985.46087234768;8988.820223595558;42604.147917041795;42950.78524607388;43032;43033.56966430327;43110.568894311284;3450.258194488172;3650.671439570451;3674.7243956703087;3718.442233664945;3968;32165.839170803996;33053.504197437054;33056.50415243773;33113;33165;4371.191329604034;4387.6709246806295;4510.1656193604285;4686;4696.671233013691;330073;332509.3374533136;336283;348840;356580;56022;56622;56870.43129568734;57028.42971570314;57052.71473642605;58834;58837;58900;58954.70522647359;58969;16961.575960601083;17212.827871721373;17224.397146099825;17229.05240211775;17441.127943603024;13601.387937542835;13660.385282662106;13697.589072327846;13709.245991470565;13710.45158193681;15781.132037738034;15783.210839457981;15797.526074217792;15801.762973555406;15809.841901580838;5946.43508866657;5951.851203719942;6135.785247516315;6171.907421388682;6214.40963108513;894.9552522373855;936.6206686292063;956.5360800011989;1026.7997740440692;1029.4080903480528;30232.84883575568;30842.69157308443;31030.68969310278;31636.525452118236;32477.675223247465;1074.8280275155937;1089.983650245247;1090.8581884354983;1098.4672433869566;1164.650604818551;15683.372665093497;16748.41380551675;16797.83202167987;16879.831201687826;16982.915085424742;448251;448907;450400;451067;452612;59375.14061585983;59478;59524;59554.87567113601;59686.403135968954;1362.5163067111232;1364.3519328319167;1370.451819272299;1401.4184113592949;1402.3619253239751;29211.269718257212;30527.389452211275;30806.229844254074;30923.845380772953;31404.842975784974;50969;51367.229491558406;51612.48387516077;51639.74180129075;51720;383670;384102;384634;384825;386128;31960.84019579887;33593.16017099592;35014.82492587521;41354.37968430416;41875.58124418717;42386;47431.52568474271;47572.524274758194;47842.52157478381;47989.76005119952;39441.802790985865;39585.478759035694;39853.60146398499;40065.799671001456;42068.78965605152;39761.16541691217;40140.79929600333;40642.18715625612;41198.1760364797;41305.380419293135;38979;39955.80022099871;40943.1811363777;40946.59053409547;40979.180416392104;32333.838330808194;35205.29589408249;35553;36391;36430.635693642726;26820.463590728465;27149;27217.644569465694;27263.591046133926;27359.86320068387 00:41:38 INFO - PROCESS | 16761 | |2;jquery.html;376.2550150701609;398.23340070364543;399.3430806323597;403.318391917659;421.42896375411317;13460.259685717281;15440.150791706452;19103.23587056518;19892;20144.39566812998;18921.905390473064;19230.423087307405;19736;19858.90070549649;19905.40283791485;5616.9608622404785;5654.151877218419;5665.8583535411535;5972.701364931758;6008.008678568053;5880.206172166767;5961.254843144607;5969.761209551612;5994;6001.729922153513;5732.197492351074;5880.176775251468;5907.793227237047;5973.193618861442;5976.103584462322;5846.590738648296;5903.232579764646;5903.409659034084;5903.645781253117;5904.498117660004;5155.381354237478;5266.8419947401635;5405.486478784548;5429.778299882534;5505.174223866412;13358.933205333911;13440.260785656785;13544.59366219015;21542.81514516701;22482.213122540634;2086.62440760663;2240.932772016842;2306.2965795432387;2380.262118743195;2694.461107778452;9476.241900647932;9904.306698531105;10119.89880101197;10393;10457.738556536071;1828.6799810033324;1839.705647096458;1897.6394485047797;1905.3331334033116;1918.0889077688125;63541.36458635354;72775.63612181958;75540.62229688872;83815;86663.13336866614;9978.301518893608;10151.187904967584;10477.266591338534;10806.51370688321;10807.567697292021;14155.433782648579;14197;14223.28883555818;14234.647708467679;14270;8130.081300812975;8272.793180170544;8316.043654979634;8538;8737.038925718165;8390.328773698211;8391.95804020976;8421.663133474714;8600;8617.267532259702;4495.123450927038;4501.15452664406;4520.118576877543;4522.140793249272;4675.7895894684825;1850.139685046433;1858.386732378312;1864.685396795256;1873.5597134673342;1958.5299528113435;895.9552022398984;896.1979028769346;904.5748498205833;919.9678011269707;1032.9586816527255;18245.27018919255;18262.36081737133;19008;19288.614227715367;19719.901400492905;945.9432434053841;982.6020461713018;985.9605615775289;997.6308765756717;1035.896410358973;10437.32157409766;11003.724906877233;11007.229493935347;11024.118070554336;11081;430689;431158;431361;438285;442656.8007399458;58438.7078064607;78260.60869695614;88101.11898881057;89295.55352223197;93650.0634993655;950.4629884115591;1036.388530766851;1080.5137688040413;1084.360227465799;1086.6088208245026;16286.592835179215;16689.66620667604;17081.23134458977;17931.19309631096;18271.1777969989;39227.21545569016;40400;42317;43130.353044704985;43154;57918;59101.40898590959;59496;59882.40117598768;60207.69896150491;8430.873536896828;8727.519986400808;8819.60311785984;8901.73294801157;9071.954640226757;11492.621595014847;13674.863251367757;13699.109557878706;13768.449262029406;14279;11852;11974.461149248478;12266.202696824677;12300.261984280973;12439.440225190068;5772.1341798728845;9484.85772713396;11584.13119016094;11748.765024699629;11786.410679466164;11014.834777478505;11388.259763115371;12083.637490875288;12192.390380480763;12498.12513124109;8793.956030219808;8849.20357167858;8882.245009174034;8947.642094316154;8959.238464730572;11232.66302010941;12028.578999734968;12139.271643701406;12254.142210045567;12389.380530973596 00:41:38 INFO - PROCESS | 16761 | |3;mootools.html;1285.4151361130687;1474.1155306815913;1492.1569313337966;1618.7490938904466;1684.2925971092131;7474.887876681854;8636.395452318333;8887.377883548146;8893.466392016484;9165.312601554893;8929.241014513758;8983.955080224607;8991.955040224806;9002.729918102452;9008.68469603564;2354.2231063748964;2380.7262164851004;2382.95234095318;2385.057902128658;2386.725526564441;2333.3583264602285;2348.9177878774244;2415.3961509622595;2415.770501802335;2418.9153379631716;2368.692070030894;2372.50177462733;2384;2411.3007227903886;2412.5777988851883;2313.398516385752;2328.767123287666;2330.778576035274;2336.4976530046038;2357.6463530470396;2305.5965206089036;2316.2472196536023;2321.1759825261956;2322.8722420266877;2324.4537533679572;8847.46915185091;8878.556072196428;8937.553122343857;8947.105289471001;8968.461892286483;5273.525382715574;5477.397486276506;5484.670919744788;5525.336959564837;5540.612157149001;3346.6151392589923;3353.195233144028;3420.743444241667;3423.1613254752497;3456.1186897341017;1126.7577470843808;1137.0789660375124;1139.3334899084045;1149.1898211760688;1162;12302.200356976768;12393.442295096742;12431.191972521758;12512.249265044036;12534.373281335897;4411.09572537629;4431.667624928144;4599.977000114978;4600.5169457207185;4625.375574297427;9822.116009559031;9822.508874556244;9827;9827.4103553787;9831.950840245752;5253.212018197301;5291.947080529222;5293.867653308698;5298.92051619226;5300.09898317287;4295.677824163137;4334.7399156050105;4376.474823021258;4487.259602165606;4969.130402179604;3495.9126021849147;3926.2736393767245;3931;3942.4677668514955;3955.406688996616;1151.5221183208876;1171.5079666540073;1176.1707995862896;1183.0949323767325;1207.7825991321647;858.3347905373386;946.2760987844305;966.6713317471953;966.9951650241704;997.4414327976357;5642;5723.54211663066;5924.851878702981;5976.820695379146;6307.337729538424;958.9760255993656;1000.9199264058858;1042.5464922758624;1100.0594491709633;1118.5805323003874;3972.602739726004;3993;4170.270202714512;4206.789660517024;4228.069824638575;10875.728106797234;11087.113030957504;11133.10935125188;11168;11226.887731122584;11124.666260012213;11140.777184456427;11149.16381271408;11151.888481115086;11154.274972126786;7802.804929876798;8165.836683266421;8169.673213071411;8331.95834020826;8341.78841146151;5986.191864098291;6124.081387791867;6140.3245642978345;6296.590721603081;6340;10188.184945204513;10210.693679189637;10214.285000049926;10307.845382319421;10315.535800888827;9298.581563829508;10048.648297309559;10462.163026957673;10598.417087059977;10622;7170.605616691132;7534.924650753423;7728.922710773045;7765.53406795594;7854.960725196566;8559.101294364002;8575.828483430172;8598.914010859811;8655.09121542229;8666.913330866862;7752;7755.92244077552;7798.610069496616;7893.486923349964;7948.8807667883875;7596.962015189889;7661.885071723817;7673.386129109769;7687.923120768721;7803.804904877196;6861.828454288683;7018.789436316919;7059.082319298458;7080.645967701491;7154.463415243774;5020.924686129784;5237.947620523746;5266.657667251617;5315.920261196008;5320.414754376938;5135.563477104488;5294.152935530296;5333.973330133324;5397.649152805055;5468.152436372368 00:41:38 INFO - PROCESS | 16761 | |4;prototype.html;321.6156692752161;324.80349388619885;333.24187473497784;333.58801879678595;335.2139233497448;5113.744312784355;5248.239005344232;5282.947170528294;5344;5374.226505253267;5047.167217409133;5169.612279079075;5202.895942081156;5221.765020574074;5236.554892834104;4138.379243113527;4139.710220284582;4147.4815648044;4155.25205463018;4163.812628431712;3973.3245348290716;4060.7462858113736;4157.397177409274;4158.482154013778;4167.395727619493;4048.9797551012202;4053.088055187574;4055.452513910614;4067.803386171464;4072.429859819628;4068.1456894052285;4074.9388759168637;4075.7758323292205;4078.775667338295;4078.9184216315507;3455.2571197192724;3590.299891521154;3599.3185077117455;3623.40213864712;3628.909277268063;5101.51535604117;5102.260172275;5109.591232701412;5114.130597798383;5124.692518448905;4683.2975053741875;4720.291956206562;4732.1718699227495;4747.395026823975;4752.120857641347;5347.59893008022;5487.506124448819;5527.751251193706;5539.5931502126305;5583.3858275589655;3968.9801550992347;3976.980115099406;3986.940195897064;3991.98004009981;3998.780676724417;5767.826965191051;5785.710714464302;5815.505682017034;5827.650340979555;5849.181114643996;3972.523297204354;4029.37544680575;4055.3105971984833;4064.4106604542767;4090.427340172378;4081.510218773766;4086.6730661547003;4089.9161722143654;4116.197341518375;4116.444280022219;4687.695299805502;4691.812327506861;4707.199776038082;4738.620910327235;4740.431148262231;5480.91778623321;5519.365272993577;5588.580856435784;5598.636088654224;5635.2249041552595;1330.5609148980814;1359.9664255166144;1402.382951501336;1444.771943847742;1450.8259008918997;929.5352323838081;930.6137952749533;941.538646063424;953.065995324572;964.9035096490295;4688.8124475021295;4699.78850951708;4704.388429504143;4721.952780472151;4755.904881902343;4935.827246046443;4941.209406494943;4967.5777558907175;4969.503049695074;4995.950040499549;4405.185040767468;4702.31816386622;4710.505396933273;4719.19208071204;4727.503612120678;2598.2205338398403;2609.7520735530093;2618.5417551928335;2624.2520881548667;2631.67104111986;5028.522290382408;5053.1157047516535;5077.933633936915;5082.720450375265;5093.898122037538;4754;5002.624803139779;5005.474425185376;5069.391672999263;5094.745262736848;5021.949780502148;5025.79380948577;5029.29589857413;5055.772490237875;5058.2821626105515;4920.220075657242;4928.383952005999;4955.702657840541;4978.850634480971;4993.575546078624;4579.9542004579525;4875.463698993037;4881.926771098365;4938.950610493993;4956.454789973172;5048.697078175322;5068.831980012492;5097.61767867404;5099.883548327402;5132.820351287687;3740.569834469071;3778.2254637799733;3786.4888240087234;3791.75353602015;3793;4855.854324370274;4969.925451118308;4970.403551573834;5004.174311238605;5063.746812659426;4829.396325459317;4853.854384368475;4864.589269111918;4890.510948905081;4917.852464426073;4783.282507623884;4815.759212039454;4827.444843843003;4871.585915197248;4878.829240976549;4650.697704649187;4669.895139716072;4791.688540244873;4819.9274312046155;4820.662553621353;4801.68789028712;4833;4873.439554451284;4874.5856602189215;4882.5605695487575;4739.881502962454;4784.330193772805;4791.904161916812;4792.760361981817;4811.49479304682 00:41:38 INFO - PROCESS | 16761 | |5;yui.html;703.3353480960493;760.6501009535612;766.6741634805206;818.8935438393008;835.9540225287615;13985.720285594281;14787.92606036971;14788.6302842429;14841.183734894577;14894.776578351333;15230.39078436864;15390.076595404256;15393.307301171446;15404.306806193748;15473.45842895496;656.6355672601704;674.6828990374516;791.8851766493855;803.7146812881431;828.5235989306173;821.4373154389233;823.3989187892842;824.11819353292;824.5629816197425;824.7484517222244;866.2809867809734;868.2099289646424;870.9303255739526;872.3849685971406;872.4285592936636;692.8891377379621;708.638594316897;836.4855613797531;854.2354592639552;866.1079088538784;633.3792882974665;633.996830015847;634.5843472525503;634.6667999300358;634.8888944434706;14885.33016014282;14929.178895160761;15035.398584056624;15054.774178387333;15067.5479735607;14061.437542498288;14128.000159278485;14481.348339324755;14646;14693.485727999469;13117.409716562777;13138.343082845819;13198;13496.662583435394;13674.384652690553;4401.119776044804;4439.156560253574;4470.910581788378;4492.797824097924;4496.752678602676;71432;72192.27807721855;84452.7773611317;88701.55649221777;89480;11412;11536.76926461466;11611.76776464466;11789;12180.330081845406;20289.289874854603;22278.777212227993;22300.107995680315;22329.55340893173;22340;8707.956460217658;8719.346049046348;8843.646254149891;8963.462192268486;9013.278937684969;4729.289462558107;4734.455537613218;4753.714777113384;4806.831760888422;4863.5622793948005;3652.7808331500196;3877.1082650989865;3887.319719049155;3915.686745060388;3917.6278253565865;646.4666650013758;657.1785268414482;657.9046038324512;666.227176475296;669.5848573884223;646.6310522179404;717.5766297884273;723.9312265334785;762.9122650895107;778.0818634011807;9274.721758347261;10041.548130334153;10193.796124077477;10210.897891021143;10325.535350909227;708.922018577956;738.4609235258291;755.3541721827759;758.3440324119597;776.5496012312846;4781.617470602413;4877.975610121926;5113.590912727047;5120.539151476385;5130.461301563357;127254;133194.33402832923;136934.3153284247;136979;138100;109104;109592;112151;112474.43762781133;115851.42074289575;63874;104919.4754026225;105371;105416;107087.99999999844;9278.768030799283;9653.951730241304;9705.5147242639;9711.27165462579;9748.122668959828;48391.27413088736;58511.70744146252;59325;59539.1069133972;59753.7012314953;72458.63770681113;76074.6196269015;77015.2298477008;77746;78067.219327806;12697.492100315883;12750.6174814752;12853.421596028014;12880;13539.11995720275;13421.261830599411;14036.010118545464;14194;14264.358103885143;14658.193799340712;13430.731385372042;13466.865331346562;13605.65985850322;13929.651758706113;14609.342579584158;13108.934455327662;13695.589132326046;14356.569302920929;14370.928145359625;14888.553343399715;13068.934655326662;13399.799003014768;13635.86364136346;14713;15133.394664213749;10678.57285708563;10920.344779313267;11128.610498632508;11261.155413343868;11514.021308188563;10568.630097946534;11129.109671226444;11196.83204751913;11196.832047519456;11385.886141138482 00:41:38 INFO - PROCESS | 16761 | __end_tp_report 00:41:38 INFO - PROCESS | 16761 | __start_cc_report 00:41:38 INFO - PROCESS | 16761 | _x_x_mozilla_cycle_collect,1176 00:41:38 INFO - PROCESS | 16761 | __end_cc_report 00:41:38 INFO - PROCESS | 16761 | __startTimestamp1474270898705__endTimestamp 00:41:38 INFO - PROCESS | 16761 | 00:41:38 INFO - PROCESS | 16761 | ------- Summary: start ------- 00:41:38 INFO - PROCESS | 16761 | Number of tests: 6 00:41:38 INFO - PROCESS | 16761 | 00:41:38 INFO - PROCESS | 16761 | [#0] dojo.html Cycles:165 Average:5367.52 Median:5517.45 stddev:612.49 (11.1%) stddev-sans-first:585.94 00:41:38 INFO - PROCESS | 16761 | Values: 3016.5 3072.7 3082.2 3106.1 3126.9 5244.9 5281.7 5394.1 5477.7 5517.4 5377.4 5439.2 5443.4 5494.0 5512.4 4812.6 4860.3 4894.9 4905.3 4979.4 4950.6 4996.5 5003.5 5011.7 5013.6 4813.4 4838.8 4849.3 4851.0 4962.2 4879.9 4950.3 4987.6 4988.5 4994.0 4836.8 4865.5 4875.2 4883.6 4934.5 5271.2 5400.3 5406.9 5413.5 5416.6 5662.3 5698.4 5766.0 5836.7 5855.8 5872.5 5889.5 5898.1 5910.5 5943.6 5451.1 5456.1 5458.1 5461.1 5464.8 5785.6 5789.2 5932.6 6005.9 6013.3 5755.5 5786.2 5794.8 5801.5 5848.9 5860.9 5876.7 5883.6 5885.3 5887.4 5497.5 5531.8 5686.5 5738.5 5841.5 5768.7 5774.2 5804.8 5827.4 5831.2 4444.9 4451.2 4456.0 4456.0 4463.3 3935.1 3954.9 3959.4 3964.6 4038.5 5485.0 5508.8 5520.2 5555.3 5570.9 5817.9 5859.1 5869.1 5871.9 5877.6 5326.1 5376.0 5500.6 5513.1 5513.8 4933.6 4985.5 4988.7 4990.3 5051.4 5994.8 5997.8 5997.9 6000.0 6005.8 5545.1 5819.6 5821.4 5822.5 5825.5 5711.2 5788.8 5941.5 5966.0 6055.3 5649.2 5679.0 5712.9 5714.7 5724.8 5770.2 5800.4 5803.4 5809.6 5816.1 5451.8 5481.9 5485.4 5518.5 5528.6 5515.0 5515.6 5515.8 5530.6 5571.6 5474.6 5474.6 5521.5 5526.9 5588.1 5515.5 5578.8 5590.3 5621.8 5623.8 5481.8 5527.7 5533.5 5592.9 5594.4 00:41:38 INFO - PROCESS | 16761 | 00:41:38 INFO - PROCESS | 16761 | [#1] ext.html Cycles:180 Average:56131.77 Median:41614.98 stddev:103971.34 (249.8%) stddev-sans-first:104179.29 00:41:38 INFO - PROCESS | 16761 | Values: 562.5 585.0 598.8 603.8 603.9 31638.0 42958.0 44117.3 44228.1 44239.5 45084.8 45446.0 45554.8 45574.5 45663.3 8933.0 9095.0 9100.4 9101.5 9105.1 8949.6 9012.4 9040.5 9040.5 9046.4 9049.0 9102.1 9111.1 9113.6 9114.8 9041.4 9070.5 9073.6 9082.2 9082.8 8916.9 8926.2 8976.0 8985.5 8988.8 42604.1 42950.8 43032.0 43033.6 43110.6 3450.3 3650.7 3674.7 3718.4 3968.0 32165.8 33053.5 33056.5 33113.0 33165.0 4371.2 4387.7 4510.2 4686.0 4696.7 330073.0 332509.3 336283.0 348840.0 356580.0 56022.0 56622.0 56870.4 57028.4 57052.7 58834.0 58837.0 58900.0 58954.7 58969.0 16961.6 17212.8 17224.4 17229.1 17441.1 13601.4 13660.4 13697.6 13709.2 13710.5 15781.1 15783.2 15797.5 15801.8 15809.8 5946.4 5951.9 6135.8 6171.9 6214.4 895.0 936.6 956.5 1026.8 1029.4 30232.8 30842.7 31030.7 31636.5 32477.7 1074.8 1090.0 1090.9 1098.5 1164.7 15683.4 16748.4 16797.8 16879.8 16982.9 448251.0 448907.0 450400.0 451067.0 452612.0 59375.1 59478.0 59524.0 59554.9 59686.4 1362.5 1364.4 1370.5 1401.4 1402.4 29211.3 30527.4 30806.2 30923.8 31404.8 50969.0 51367.2 51612.5 51639.7 51720.0 383670.0 384102.0 384634.0 384825.0 386128.0 31960.8 33593.2 35014.8 41354.4 41875.6 42386.0 47431.5 47572.5 47842.5 47989.8 39441.8 39585.5 39853.6 40065.8 42068.8 39761.2 40140.8 40642.2 41198.2 41305.4 38979.0 39955.8 40943.2 40946.6 40979.2 32333.8 35205.3 35553.0 36391.0 36430.6 26820.5 27149.0 27217.6 27263.6 27359.9 00:41:38 INFO - PROCESS | 16761 | 00:41:38 INFO - PROCESS | 16761 | [#2] jquery.html Cycles:180 Average:26848.50 Median:20401.66 stddev:71796.93 (351.9%) stddev-sans-first:71970.83 00:41:38 INFO - PROCESS | 16761 | Values: 376.3 398.2 399.3 403.3 421.4 13460.3 15440.2 19103.2 19892.0 20144.4 18921.9 19230.4 19736.0 19858.9 19905.4 5617.0 5654.2 5665.9 5972.7 6008.0 5880.2 5961.3 5969.8 5994.0 6001.7 5732.2 5880.2 5907.8 5973.2 5976.1 5846.6 5903.2 5903.4 5903.6 5904.5 5155.4 5266.8 5405.5 5429.8 5505.2 13358.9 13440.3 13544.6 21542.8 22482.2 2086.6 2240.9 2306.3 2380.3 2694.5 9476.2 9904.3 10119.9 10393.0 10457.7 1828.7 1839.7 1897.6 1905.3 1918.1 63541.4 72775.6 75540.6 83815.0 86663.1 9978.3 10151.2 10477.3 10806.5 10807.6 14155.4 14197.0 14223.3 14234.6 14270.0 8130.1 8272.8 8316.0 8538.0 8737.0 8390.3 8392.0 8421.7 8600.0 8617.3 4495.1 4501.2 4520.1 4522.1 4675.8 1850.1 1858.4 1864.7 1873.6 1958.5 896.0 896.2 904.6 920.0 1033.0 18245.3 18262.4 19008.0 19288.6 19719.9 945.9 982.6 986.0 997.6 1035.9 10437.3 11003.7 11007.2 11024.1 11081.0 430689.0 431158.0 431361.0 438285.0 442656.8 58438.7 78260.6 88101.1 89295.6 93650.1 950.5 1036.4 1080.5 1084.4 1086.6 16286.6 16689.7 17081.2 17931.2 18271.2 39227.2 40400.0 42317.0 43130.4 43154.0 57918.0 59101.4 59496.0 59882.4 60207.7 8430.9 8727.5 8819.6 8901.7 9072.0 11492.6 13674.9 13699.1 13768.4 14279.0 11852.0 11974.5 12266.2 12300.3 12439.4 5772.1 9484.9 11584.1 11748.8 11786.4 11014.8 11388.3 12083.6 12192.4 12498.1 8794.0 8849.2 8882.2 8947.6 8959.2 11232.7 12028.6 12139.3 12254.1 12389.4 00:41:38 INFO - PROCESS | 16761 | 00:41:38 INFO - PROCESS | 16761 | [#3] mootools.html Cycles:180 Average:5828.32 Median:4612.95 stddev:3318.59 (71.9%) stddev-sans-first:3310.34 00:41:38 INFO - PROCESS | 16761 | Values: 1285.4 1474.1 1492.2 1618.7 1684.3 7474.9 8636.4 8887.4 8893.5 9165.3 8929.2 8984.0 8992.0 9002.7 9008.7 2354.2 2380.7 2383.0 2385.1 2386.7 2333.4 2348.9 2415.4 2415.8 2418.9 2368.7 2372.5 2384.0 2411.3 2412.6 2313.4 2328.8 2330.8 2336.5 2357.6 2305.6 2316.2 2321.2 2322.9 2324.5 8847.5 8878.6 8937.6 8947.1 8968.5 5273.5 5477.4 5484.7 5525.3 5540.6 3346.6 3353.2 3420.7 3423.2 3456.1 1126.8 1137.1 1139.3 1149.2 1162.0 12302.2 12393.4 12431.2 12512.2 12534.4 4411.1 4431.7 4600.0 4600.5 4625.4 9822.1 9822.5 9827.0 9827.4 9832.0 5253.2 5291.9 5293.9 5298.9 5300.1 4295.7 4334.7 4376.5 4487.3 4969.1 3495.9 3926.3 3931.0 3942.5 3955.4 1151.5 1171.5 1176.2 1183.1 1207.8 858.3 946.3 966.7 967.0 997.4 5642.0 5723.5 5924.9 5976.8 6307.3 959.0 1000.9 1042.5 1100.1 1118.6 3972.6 3993.0 4170.3 4206.8 4228.1 10875.7 11087.1 11133.1 11168.0 11226.9 11124.7 11140.8 11149.2 11151.9 11154.3 7802.8 8165.8 8169.7 8332.0 8341.8 5986.2 6124.1 6140.3 6296.6 6340.0 10188.2 10210.7 10214.3 10307.8 10315.5 9298.6 10048.6 10462.2 10598.4 10622.0 7170.6 7534.9 7728.9 7765.5 7855.0 8559.1 8575.8 8598.9 8655.1 8666.9 7752.0 7755.9 7798.6 7893.5 7948.9 7597.0 7661.9 7673.4 7687.9 7803.8 6861.8 7018.8 7059.1 7080.6 7154.5 5020.9 5237.9 5266.7 5315.9 5320.4 5135.6 5294.2 5334.0 5397.6 5468.2 00:41:38 INFO - PROCESS | 16761 | 00:41:38 INFO - PROCESS | 16761 | [#4] prototype.html Cycles:180 Average:4344.61 Median:4806.59 stddev:1216.93 (25.3%) stddev-sans-first:1182.28 00:41:38 INFO - PROCESS | 16761 | Values: 321.6 324.8 333.2 333.6 335.2 5113.7 5248.2 5282.9 5344.0 5374.2 5047.2 5169.6 5202.9 5221.8 5236.6 4138.4 4139.7 4147.5 4155.3 4163.8 3973.3 4060.7 4157.4 4158.5 4167.4 4049.0 4053.1 4055.5 4067.8 4072.4 4068.1 4074.9 4075.8 4078.8 4078.9 3455.3 3590.3 3599.3 3623.4 3628.9 5101.5 5102.3 5109.6 5114.1 5124.7 4683.3 4720.3 4732.2 4747.4 4752.1 5347.6 5487.5 5527.8 5539.6 5583.4 3969.0 3977.0 3986.9 3992.0 3998.8 5767.8 5785.7 5815.5 5827.7 5849.2 3972.5 4029.4 4055.3 4064.4 4090.4 4081.5 4086.7 4089.9 4116.2 4116.4 4687.7 4691.8 4707.2 4738.6 4740.4 5480.9 5519.4 5588.6 5598.6 5635.2 1330.6 1360.0 1402.4 1444.8 1450.8 929.5 930.6 941.5 953.1 964.9 4688.8 4699.8 4704.4 4722.0 4755.9 4935.8 4941.2 4967.6 4969.5 4996.0 4405.2 4702.3 4710.5 4719.2 4727.5 2598.2 2609.8 2618.5 2624.3 2631.7 5028.5 5053.1 5077.9 5082.7 5093.9 4754.0 5002.6 5005.5 5069.4 5094.7 5021.9 5025.8 5029.3 5055.8 5058.3 4920.2 4928.4 4955.7 4978.9 4993.6 4580.0 4875.5 4881.9 4939.0 4956.5 5048.7 5068.8 5097.6 5099.9 5132.8 3740.6 3778.2 3786.5 3791.8 3793.0 4855.9 4969.9 4970.4 5004.2 5063.7 4829.4 4853.9 4864.6 4890.5 4917.9 4783.3 4815.8 4827.4 4871.6 4878.8 4650.7 4669.9 4791.7 4819.9 4820.7 4801.7 4833.0 4873.4 4874.6 4882.6 4739.9 4784.3 4791.9 4792.8 4811.5 00:41:38 INFO - PROCESS | 16761 | 00:41:38 INFO - PROCESS | 16761 | [#5] yui.html Cycles:180 Average:22476.42 Median:3901.50 stddev:33918.98 (869.4%) stddev-sans-first:33974.73 00:41:38 INFO - PROCESS | 16761 | Values: 703.3 760.7 766.7 818.9 836.0 13985.7 14787.9 14788.6 14841.2 14894.8 15230.4 15390.1 15393.3 15404.3 15473.5 656.6 674.7 791.9 803.7 828.5 821.4 823.4 824.1 824.6 824.7 866.3 868.2 870.9 872.4 872.4 692.9 708.6 836.5 854.2 866.1 633.4 634.0 634.6 634.7 634.9 14885.3 14929.2 15035.4 15054.8 15067.5 14061.4 14128.0 14481.3 14646.0 14693.5 13117.4 13138.3 13198.0 13496.7 13674.4 4401.1 4439.2 4470.9 4492.8 4496.8 71432.0 72192.3 84452.8 88701.6 89480.0 11412.0 11536.8 11611.8 11789.0 12180.3 20289.3 22278.8 22300.1 22329.6 22340.0 8708.0 8719.3 8843.6 8963.5 9013.3 4729.3 4734.5 4753.7 4806.8 4863.6 3652.8 3877.1 3887.3 3915.7 3917.6 646.5 657.2 657.9 666.2 669.6 646.6 717.6 723.9 762.9 778.1 9274.7 10041.5 10193.8 10210.9 10325.5 708.9 738.5 755.4 758.3 776.5 4781.6 4878.0 5113.6 5120.5 5130.5 127254.0 133194.3 136934.3 136979.0 138100.0 109104.0 109592.0 112151.0 112474.4 115851.4 63874.0 104919.5 105371.0 105416.0 107088.0 9278.8 9654.0 9705.5 9711.3 9748.1 48391.3 58511.7 59325.0 59539.1 59753.7 72458.6 76074.6 77015.2 77746.0 78067.2 12697.5 12750.6 12853.4 12880.0 13539.1 13421.3 14036.0 14194.0 14264.4 14658.2 13430.7 13466.9 13605.7 13929.7 14609.3 13108.9 13695.6 14356.6 14370.9 14888.6 13068.9 13399.8 13635.9 14713.0 15133.4 10678.6 10920.3 11128.6 11261.2 11514.0 10568.6 11129.1 11196.8 11196.8 11385.9 00:41:38 INFO - PROCESS | 16761 | -------- Summary: end -------- 00:41:38 INFO - PROCESS | 16761 | 00:41:39 INFO - TEST-INFO | 16761: exit 0 00:41:39 INFO - TEST-OK | dromaeo_css | took 1115618ms 00:41:39 INFO - TEST-START | kraken 00:41:39 INFO - operating with platform_type : linux_ 00:41:39 INFO - Initialising browser for kraken test... 00:41:39 INFO - TEST-INFO | started process 17064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO84Xrd/profile http://localhost:51320/getInfo.html) 00:41:43 INFO - PROCESS | 17064 | __metrics Screen width/height:1600/1200 00:41:43 INFO - PROCESS | 17064 | colorDepth:24 00:41:43 INFO - PROCESS | 17064 | Browser inner width/height: 1024/697 00:41:43 INFO - PROCESS | 17064 | __metrics 00:41:44 INFO - PROCESS | 17064 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 00:41:44 INFO - PROCESS | 17064 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 00:41:44 INFO - PROCESS | 17064 | console.error: PushService: 00:41:44 INFO - PROCESS | 17064 | stateChangeProcessEnqueue: Error transitioning state 00:41:44 INFO - PROCESS | 17064 | UnknownError 00:41:45 INFO - TEST-INFO | 17064: exit 0 00:41:45 INFO - Browser initialized. 00:41:45 INFO - Running cycle 1/1 for kraken test... 00:41:45 INFO - TEST-INFO | started process 17148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO84Xrd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:41:57 INFO - PROCESS | 17148 | RSS: Main: 173858816 00:41:57 INFO - PROCESS | 17148 | 00:41:59 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51320/tests/kraken/driver.html?testName=audio-beat-detection) 00:42:00 INFO - PROCESS | 17148 | RSS: Main: 232431616 00:42:00 INFO - PROCESS | 17148 | 00:42:03 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51320/tests/kraken/driver.html?testName=audio-dft) 00:42:03 INFO - PROCESS | 17148 | RSS: Main: 216924160 00:42:03 INFO - PROCESS | 17148 | 00:42:06 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51320/tests/kraken/driver.html?testName=audio-fft) 00:42:07 INFO - PROCESS | 17148 | RSS: Main: 298536960 00:42:07 INFO - PROCESS | 17148 | 00:42:09 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51320/tests/kraken/driver.html?testName=audio-oscillator) 00:42:10 INFO - PROCESS | 17148 | RSS: Main: 209219584 00:42:10 INFO - PROCESS | 17148 | 00:42:12 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:42:12 INFO - PROCESS | 17148 | RSS: Main: 213196800 00:42:12 INFO - PROCESS | 17148 | 00:42:15 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51320/tests/kraken/driver.html?testName=imaging-darkroom) 00:42:16 INFO - PROCESS | 17148 | RSS: Main: 249708544 00:42:16 INFO - PROCESS | 17148 | 00:42:19 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51320/tests/kraken/driver.html?testName=imaging-desaturate) 00:42:20 INFO - PROCESS | 17148 | RSS: Main: 248418304 00:42:20 INFO - PROCESS | 17148 | 00:42:23 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51320/tests/kraken/driver.html?testName=json-parse-financial) 00:42:23 INFO - PROCESS | 17148 | RSS: Main: 248320000 00:42:23 INFO - PROCESS | 17148 | 00:42:25 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:42:25 INFO - PROCESS | 17148 | RSS: Main: 210513920 00:42:25 INFO - PROCESS | 17148 | 00:42:27 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:42:28 INFO - PROCESS | 17148 | RSS: Main: 218341376 00:42:28 INFO - PROCESS | 17148 | 00:42:30 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:42:30 INFO - PROCESS | 17148 | RSS: Main: 222031872 00:42:30 INFO - PROCESS | 17148 | 00:42:33 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:42:34 INFO - PROCESS | 17148 | RSS: Main: 222789632 00:42:34 INFO - PROCESS | 17148 | 00:42:36 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:42:37 INFO - PROCESS | 17148 | RSS: Main: 220696576 00:42:37 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | Cycle 1(1): loaded http://localhost:51320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51320/tests/kraken/driver.html?testName=ai-astar) 00:42:39 INFO - PROCESS | 17148 | RSS: Main: 272195584 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | __start_tp_report 00:42:39 INFO - PROCESS | 17148 | _x_x_mozilla_page_load 00:42:39 INFO - PROCESS | 17148 | _x_x_mozilla_page_load_details 00:42:39 INFO - PROCESS | 17148 | |i|pagename|runs| 00:42:39 INFO - PROCESS | 17148 | |0;ai-astar;108;111;111;112;112;111;110;110;110;111 00:42:39 INFO - PROCESS | 17148 | |1;audio-beat-detection;142;120;119;155;119;121;122;124;151;119 00:42:39 INFO - PROCESS | 17148 | |2;audio-dft;162;164;161;161;161;161;160;162;160;161 00:42:39 INFO - PROCESS | 17148 | |3;audio-fft;74;74;75;102;75;75;77;75;118;75 00:42:39 INFO - PROCESS | 17148 | |4;audio-oscillator;83;82;112;81;80;82;100;82;83;110 00:42:39 INFO - PROCESS | 17148 | |5;imaging-gaussian-blur;117;117;117;116;117;117;118;116;117;117 00:42:39 INFO - PROCESS | 17148 | |6;imaging-darkroom;146;145;146;146;146;146;146;146;146;146 00:42:39 INFO - PROCESS | 17148 | |7;imaging-desaturate;96;95;95;95;95;96;95;95;95;96 00:42:39 INFO - PROCESS | 17148 | |8;json-parse-financial;53;51;56;50;52;51;51;52;51;51 00:42:39 INFO - PROCESS | 17148 | |9;json-stringify-tinderbox;60;61;60;61;61;60;61;60;61;61 00:42:39 INFO - PROCESS | 17148 | |10;stanford-crypto-aes;80;93;82;79;85;79;78;78;78;78 00:42:39 INFO - PROCESS | 17148 | |11;stanford-crypto-ccm;124;131;124;127;132;129;124;124;161;123 00:42:39 INFO - PROCESS | 17148 | |12;stanford-crypto-pbkdf2;164;166;168;167;164;164;162;163;166;166 00:42:39 INFO - PROCESS | 17148 | |13;stanford-crypto-sha256-iterative;68;67;64;68;64;66;65;64;65;65 00:42:39 INFO - PROCESS | 17148 | __end_tp_report 00:42:39 INFO - PROCESS | 17148 | __start_cc_report 00:42:39 INFO - PROCESS | 17148 | _x_x_mozilla_cycle_collect,1607 00:42:39 INFO - PROCESS | 17148 | __end_cc_report 00:42:39 INFO - PROCESS | 17148 | __startTimestamp1474270959445__endTimestamp 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | ------- Summary: start ------- 00:42:39 INFO - PROCESS | 17148 | Number of tests: 14 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:1.17 (1.1%) stddev-sans-first:0.78 00:42:39 INFO - PROCESS | 17148 | Values: 108.0 111.0 111.0 112.0 112.0 111.0 110.0 110.0 110.0 111.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:123.00 stddev:14.33 (11.6%) stddev-sans-first:14.43 00:42:39 INFO - PROCESS | 17148 | Values: 142.0 120.0 119.0 155.0 119.0 121.0 122.0 124.0 151.0 119.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 00:42:39 INFO - PROCESS | 17148 | Values: 162.0 164.0 161.0 161.0 161.0 161.0 160.0 162.0 160.0 161.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#3] audio-fft Cycles:10 Average:82.00 Median:75.00 stddev:15.25 (20.3%) stddev-sans-first:15.90 00:42:39 INFO - PROCESS | 17148 | Values: 74.0 74.0 75.0 102.0 75.0 75.0 77.0 75.0 118.0 75.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:82.00 stddev:12.70 (15.5%) stddev-sans-first:13.25 00:42:39 INFO - PROCESS | 17148 | Values: 83.0 82.0 112.0 81.0 80.0 82.0 100.0 82.0 83.0 110.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 00:42:39 INFO - PROCESS | 17148 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 118.0 116.0 117.0 117.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 00:42:39 INFO - PROCESS | 17148 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 00:42:39 INFO - PROCESS | 17148 | Values: 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 96.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:51.50 stddev:1.69 (3.3%) stddev-sans-first:1.73 00:42:39 INFO - PROCESS | 17148 | Values: 53.0 51.0 56.0 50.0 52.0 51.0 51.0 52.0 51.0 51.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:61.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 00:42:39 INFO - PROCESS | 17148 | Values: 60.0 61.0 60.0 61.0 61.0 60.0 61.0 60.0 61.0 61.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:79.50 stddev:4.78 (6.0%) stddev-sans-first:5.06 00:42:39 INFO - PROCESS | 17148 | Values: 80.0 93.0 82.0 79.0 85.0 79.0 78.0 78.0 78.0 78.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#11] stanford-crypto-ccm Cycles:10 Average:129.90 Median:128.00 stddev:11.40 (8.9%) stddev-sans-first:11.89 00:42:39 INFO - PROCESS | 17148 | Values: 124.0 131.0 124.0 127.0 132.0 129.0 124.0 124.0 161.0 123.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.00 stddev:1.89 (1.1%) stddev-sans-first:1.96 00:42:39 INFO - PROCESS | 17148 | Values: 164.0 166.0 168.0 167.0 164.0 164.0 162.0 163.0 166.0 166.0 00:42:39 INFO - PROCESS | 17148 | 00:42:39 INFO - PROCESS | 17148 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:1.58 (2.4%) stddev-sans-first:1.41 00:42:39 INFO - PROCESS | 17148 | Values: 68.0 67.0 64.0 68.0 64.0 66.0 65.0 64.0 65.0 65.0 00:42:39 INFO - PROCESS | 17148 | -------- Summary: end -------- 00:42:39 INFO - PROCESS | 17148 | 00:42:40 INFO - TEST-INFO | 17148: exit 0 00:42:40 INFO - TEST-OK | kraken | took 60862ms 00:42:40 INFO - SUITE-END | took 1176s 00:42:40 INFO - Completed test suite (00:19:36) 00:42:40 INFO - javascript benchmark 00:42:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3016.48719717648, 3072.677368876268, 3082.2448500117466, 3106.0526539405473, 3126.937461250775, 5244.921326180101, 5281.735913204334, 5394.109971854644, 5477.726113694319, 5517.448255174486, 5377.4084850666395, 5439.211314359415, 5443.428440013801, 5494.038543254935, 5512.4211957744465, 4812.614990800727, 4860.3195552622665, 4894.926576101361, 4905.337779399771, 4979.427365852919, 4950.603951683875, 4996.525330093654, 5003.47463516331, 5011.699298042129, 5013.623978201631, 4813.446453657839, 4838.830640927551, 4849.296851956482, 4850.975745121252, 4962.206047032476, 4879.8780030499165, 4950.257461380786, 4987.62592805541, 4988.501149885019, 4994.0, 4836.758162091881, 4865.5134486551415, 4875.195592727196, 4883.584895283905, 4934.506549345073, 5271.209318602202, 5400.324959380077, 5406.8918621627345, 5413.458654134594, 5416.566674666056, 5662.292213473316, 5698.43015698431, 5766.0, 5836.708164591796, 5855.824325270206, 5872.500837428824, 5889.469947704727, 5898.115282707586, 5910.468057874769, 5943.643381397087, 5451.127819548853, 5456.127019676912, 5458.1266997280245, 5461.098918678414, 5464.808731694372, 5785.595008349459, 5789.247397838339, 5932.614380065282, 6005.909911351333, 6013.338532761392, 5755.482006619341, 5786.247787787561, 5794.82615521535, 5801.477866991907, 5848.912266316009, 5860.941390586039, 5876.676782776903, 5883.558733095035, 5885.293764748258, 5887.35239123696, 5497.505224529811, 5531.834044978657, 5686.459786320293, 5738.454846789548, 5841.532677385799, 5768.711564421762, 5774.162746401748, 5804.767809287666, 5827.446392592697, 5831.154482599999, 4444.933326000112, 4451.221036318631, 4456.019675671347, 4456.041950980555, 4463.330500424897, 3935.0752573145287, 3954.940675889864, 3959.366501359826, 3964.6035396460125, 4038.4749982502085, 5485.01269771445, 5508.834734957958, 5520.227168196456, 5555.30558680165, 5570.860728481739, 5817.883642327214, 5859.091840764689, 5869.090291004901, 5871.853203669943, 5877.647341159544, 5326.121190003606, 5376.032314183484, 5500.614956952975, 5513.062779327524, 5513.751881165357, 4933.605311575066, 4985.501449854985, 4988.6757360771435, 4990.251462280687, 5051.393832740021, 5994.820155395345, 5997.820065398045, 5997.850053748692, 5999.970000149971, 6005.789797357072, 5545.085060965057, 5819.5635327351465, 5821.38875418075, 5822.505087067478, 5825.475707186373, 5711.228984087095, 5788.797392091256, 5941.465268125889, 5966.0, 6055.333913269449, 5649.152627105967, 5678.971605142113, 5712.857178570569, 5714.685692286964, 5724.771009159754, 5770.221020162225, 5800.39095894925, 5803.448672376117, 5809.564282678901, 5816.098504731774, 5451.809186678447, 5481.917771233514, 5485.369182543899, 5518.475744804237, 5528.585356098389, 5514.972425137848, 5515.61390702663, 5515.779368825202, 5530.640508366943, 5571.554275657856, 5474.5894057945015, 5474.589405794661, 5521.503064724194, 5526.944730552804, 5588.077967135378, 5515.531179849758, 5578.77684892616, 5590.329160500766, 5621.803236886853, 5623.775048998158, 5481.808136715196, 5527.695976721319, 5533.474319939599, 5592.86017849557, 5594.38461769197], "value": 5325.791233193579, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.4644238839952, 584.9590528662994, 598.8353202869213, 603.7735849056604, 603.891299566078, 31638.0, 42958.0, 44117.33823992635, 44228.11543769127, 44239.451619193336, 45084.77457612707, 45446.0, 45554.772226138826, 45574.54425455745, 45663.31505027427, 8932.955335223314, 9095.045020272875, 9100.362974591748, 9101.453912765255, 9105.135012173832, 8949.64201431942, 9012.41419307743, 9040.547972601376, 9040.547972601376, 9046.3667543272, 9049.049849765779, 9102.13529714676, 9111.088891110901, 9113.635454581809, 9114.77213069672, 9041.3671043027, 9070.54647267634, 9073.59168837404, 9082.228010619105, 9082.77293067672, 8916.910830891673, 8926.196642302224, 8976.0, 8985.46087234768, 8988.820223595558, 42604.147917041795, 42950.78524607388, 43032.0, 43033.56966430327, 43110.568894311284, 3450.258194488172, 3650.671439570451, 3674.7243956703087, 3718.442233664945, 3968.0, 32165.839170803996, 33053.504197437054, 33056.50415243773, 33113.0, 33165.0, 4371.191329604034, 4387.6709246806295, 4510.1656193604285, 4686.0, 4696.671233013691, 330073.0, 332509.3374533136, 336283.0, 348840.0, 356580.0, 56022.0, 56622.0, 56870.43129568734, 57028.42971570314, 57052.71473642605, 58834.0, 58837.0, 58900.0, 58954.70522647359, 58969.0, 16961.575960601083, 17212.827871721373, 17224.397146099825, 17229.05240211775, 17441.127943603024, 13601.387937542835, 13660.385282662106, 13697.589072327846, 13709.245991470565, 13710.45158193681, 15781.132037738034, 15783.210839457981, 15797.526074217792, 15801.762973555406, 15809.841901580838, 5946.43508866657, 5951.851203719942, 6135.785247516315, 6171.907421388682, 6214.40963108513, 894.9552522373855, 936.6206686292063, 956.5360800011989, 1026.7997740440692, 1029.4080903480528, 30232.84883575568, 30842.69157308443, 31030.68969310278, 31636.525452118236, 32477.675223247465, 1074.8280275155937, 1089.983650245247, 1090.8581884354983, 1098.4672433869566, 1164.650604818551, 15683.372665093497, 16748.41380551675, 16797.83202167987, 16879.831201687826, 16982.915085424742, 448251.0, 448907.0, 450400.0, 451067.0, 452612.0, 59375.14061585983, 59478.0, 59524.0, 59554.87567113601, 59686.403135968954, 1362.5163067111232, 1364.3519328319167, 1370.451819272299, 1401.4184113592949, 1402.3619253239751, 29211.269718257212, 30527.389452211275, 30806.229844254074, 30923.845380772953, 31404.842975784974, 50969.0, 51367.229491558406, 51612.48387516077, 51639.74180129075, 51720.0, 383670.0, 384102.0, 384634.0, 384825.0, 386128.0, 31960.84019579887, 33593.16017099592, 35014.82492587521, 41354.37968430416, 41875.58124418717, 42386.0, 47431.52568474271, 47572.524274758194, 47842.52157478381, 47989.76005119952, 39441.802790985865, 39585.478759035694, 39853.60146398499, 40065.799671001456, 42068.78965605152, 39761.16541691217, 40140.79929600333, 40642.18715625612, 41198.1760364797, 41305.380419293135, 38979.0, 39955.80022099871, 40943.1811363777, 40946.59053409547, 40979.180416392104, 32333.838330808194, 35205.29589408249, 35553.0, 36391.0, 36430.635693642726, 26820.463590728465, 27149.0, 27217.644569465694, 27263.591046133926, 27359.86320068387], "value": 19879.08671327911, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.2550150701609, 398.23340070364543, 399.3430806323597, 403.318391917659, 421.42896375411317, 13460.259685717281, 15440.150791706452, 19103.23587056518, 19892.0, 20144.39566812998, 18921.905390473064, 19230.423087307405, 19736.0, 19858.90070549649, 19905.40283791485, 5616.9608622404785, 5654.151877218419, 5665.8583535411535, 5972.701364931758, 6008.008678568053, 5880.206172166767, 5961.254843144607, 5969.761209551612, 5994.0, 6001.729922153513, 5732.197492351074, 5880.176775251468, 5907.793227237047, 5973.193618861442, 5976.103584462322, 5846.590738648296, 5903.232579764646, 5903.409659034084, 5903.645781253117, 5904.498117660004, 5155.381354237478, 5266.8419947401635, 5405.486478784548, 5429.778299882534, 5505.174223866412, 13358.933205333911, 13440.260785656785, 13544.59366219015, 21542.81514516701, 22482.213122540634, 2086.62440760663, 2240.932772016842, 2306.2965795432387, 2380.262118743195, 2694.461107778452, 9476.241900647932, 9904.306698531105, 10119.89880101197, 10393.0, 10457.738556536071, 1828.6799810033324, 1839.705647096458, 1897.6394485047797, 1905.3331334033116, 1918.0889077688125, 63541.36458635354, 72775.63612181958, 75540.62229688872, 83815.0, 86663.13336866614, 9978.301518893608, 10151.187904967584, 10477.266591338534, 10806.51370688321, 10807.567697292021, 14155.433782648579, 14197.0, 14223.28883555818, 14234.647708467679, 14270.0, 8130.081300812975, 8272.793180170544, 8316.043654979634, 8538.0, 8737.038925718165, 8390.328773698211, 8391.95804020976, 8421.663133474714, 8600.0, 8617.267532259702, 4495.123450927038, 4501.15452664406, 4520.118576877543, 4522.140793249272, 4675.7895894684825, 1850.139685046433, 1858.386732378312, 1864.685396795256, 1873.5597134673342, 1958.5299528113435, 895.9552022398984, 896.1979028769346, 904.5748498205833, 919.9678011269707, 1032.9586816527255, 18245.27018919255, 18262.36081737133, 19008.0, 19288.614227715367, 19719.901400492905, 945.9432434053841, 982.6020461713018, 985.9605615775289, 997.6308765756717, 1035.896410358973, 10437.32157409766, 11003.724906877233, 11007.229493935347, 11024.118070554336, 11081.0, 430689.0, 431158.0, 431361.0, 438285.0, 442656.8007399458, 58438.7078064607, 78260.60869695614, 88101.11898881057, 89295.55352223197, 93650.0634993655, 950.4629884115591, 1036.388530766851, 1080.5137688040413, 1084.360227465799, 1086.6088208245026, 16286.592835179215, 16689.66620667604, 17081.23134458977, 17931.19309631096, 18271.1777969989, 39227.21545569016, 40400.0, 42317.0, 43130.353044704985, 43154.0, 57918.0, 59101.40898590959, 59496.0, 59882.40117598768, 60207.69896150491, 8430.873536896828, 8727.519986400808, 8819.60311785984, 8901.73294801157, 9071.954640226757, 11492.621595014847, 13674.863251367757, 13699.109557878706, 13768.449262029406, 14279.0, 11852.0, 11974.461149248478, 12266.202696824677, 12300.261984280973, 12439.440225190068, 5772.1341798728845, 9484.85772713396, 11584.13119016094, 11748.765024699629, 11786.410679466164, 11014.834777478505, 11388.259763115371, 12083.637490875288, 12192.390380480763, 12498.12513124109, 8793.956030219808, 8849.20357167858, 8882.245009174034, 8947.642094316154, 8959.238464730572, 11232.66302010941, 12028.578999734968, 12139.271643701406, 12254.142210045567, 12389.380530973596], "value": 9026.614688876294, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1285.4151361130687, 1474.1155306815913, 1492.1569313337966, 1618.7490938904466, 1684.2925971092131, 7474.887876681854, 8636.395452318333, 8887.377883548146, 8893.466392016484, 9165.312601554893, 8929.241014513758, 8983.955080224607, 8991.955040224806, 9002.729918102452, 9008.68469603564, 2354.2231063748964, 2380.7262164851004, 2382.95234095318, 2385.057902128658, 2386.725526564441, 2333.3583264602285, 2348.9177878774244, 2415.3961509622595, 2415.770501802335, 2418.9153379631716, 2368.692070030894, 2372.50177462733, 2384.0, 2411.3007227903886, 2412.5777988851883, 2313.398516385752, 2328.767123287666, 2330.778576035274, 2336.4976530046038, 2357.6463530470396, 2305.5965206089036, 2316.2472196536023, 2321.1759825261956, 2322.8722420266877, 2324.4537533679572, 8847.46915185091, 8878.556072196428, 8937.553122343857, 8947.105289471001, 8968.461892286483, 5273.525382715574, 5477.397486276506, 5484.670919744788, 5525.336959564837, 5540.612157149001, 3346.6151392589923, 3353.195233144028, 3420.743444241667, 3423.1613254752497, 3456.1186897341017, 1126.7577470843808, 1137.0789660375124, 1139.3334899084045, 1149.1898211760688, 1162.0, 12302.200356976768, 12393.442295096742, 12431.191972521758, 12512.249265044036, 12534.373281335897, 4411.09572537629, 4431.667624928144, 4599.977000114978, 4600.5169457207185, 4625.375574297427, 9822.116009559031, 9822.508874556244, 9827.0, 9827.4103553787, 9831.950840245752, 5253.212018197301, 5291.947080529222, 5293.867653308698, 5298.92051619226, 5300.09898317287, 4295.677824163137, 4334.7399156050105, 4376.474823021258, 4487.259602165606, 4969.130402179604, 3495.9126021849147, 3926.2736393767245, 3931.0, 3942.4677668514955, 3955.406688996616, 1151.5221183208876, 1171.5079666540073, 1176.1707995862896, 1183.0949323767325, 1207.7825991321647, 858.3347905373386, 946.2760987844305, 966.6713317471953, 966.9951650241704, 997.4414327976357, 5642.0, 5723.54211663066, 5924.851878702981, 5976.820695379146, 6307.337729538424, 958.9760255993656, 1000.9199264058858, 1042.5464922758624, 1100.0594491709633, 1118.5805323003874, 3972.602739726004, 3993.0, 4170.270202714512, 4206.789660517024, 4228.069824638575, 10875.728106797234, 11087.113030957504, 11133.10935125188, 11168.0, 11226.887731122584, 11124.666260012213, 11140.777184456427, 11149.16381271408, 11151.888481115086, 11154.274972126786, 7802.804929876798, 8165.836683266421, 8169.673213071411, 8331.95834020826, 8341.78841146151, 5986.191864098291, 6124.081387791867, 6140.3245642978345, 6296.590721603081, 6340.0, 10188.184945204513, 10210.693679189637, 10214.285000049926, 10307.845382319421, 10315.535800888827, 9298.581563829508, 10048.648297309559, 10462.163026957673, 10598.417087059977, 10622.0, 7170.605616691132, 7534.924650753423, 7728.922710773045, 7765.53406795594, 7854.960725196566, 8559.101294364002, 8575.828483430172, 8598.914010859811, 8655.09121542229, 8666.913330866862, 7752.0, 7755.92244077552, 7798.610069496616, 7893.486923349964, 7948.8807667883875, 7596.962015189889, 7661.885071723817, 7673.386129109769, 7687.923120768721, 7803.804904877196, 6861.828454288683, 7018.789436316919, 7059.082319298458, 7080.645967701491, 7154.463415243774, 5020.924686129784, 5237.947620523746, 5266.657667251617, 5315.920261196008, 5320.414754376938, 5135.563477104488, 5294.152935530296, 5333.973330133324, 5397.649152805055, 5468.152436372368], "value": 4662.77464576643, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.6156692752161, 324.80349388619885, 333.24187473497784, 333.58801879678595, 335.2139233497448, 5113.744312784355, 5248.239005344232, 5282.947170528294, 5344.0, 5374.226505253267, 5047.167217409133, 5169.612279079075, 5202.895942081156, 5221.765020574074, 5236.554892834104, 4138.379243113527, 4139.710220284582, 4147.4815648044, 4155.25205463018, 4163.812628431712, 3973.3245348290716, 4060.7462858113736, 4157.397177409274, 4158.482154013778, 4167.395727619493, 4048.9797551012202, 4053.088055187574, 4055.452513910614, 4067.803386171464, 4072.429859819628, 4068.1456894052285, 4074.9388759168637, 4075.7758323292205, 4078.775667338295, 4078.9184216315507, 3455.2571197192724, 3590.299891521154, 3599.3185077117455, 3623.40213864712, 3628.909277268063, 5101.51535604117, 5102.260172275, 5109.591232701412, 5114.130597798383, 5124.692518448905, 4683.2975053741875, 4720.291956206562, 4732.1718699227495, 4747.395026823975, 4752.120857641347, 5347.59893008022, 5487.506124448819, 5527.751251193706, 5539.5931502126305, 5583.3858275589655, 3968.9801550992347, 3976.980115099406, 3986.940195897064, 3991.98004009981, 3998.780676724417, 5767.826965191051, 5785.710714464302, 5815.505682017034, 5827.650340979555, 5849.181114643996, 3972.523297204354, 4029.37544680575, 4055.3105971984833, 4064.4106604542767, 4090.427340172378, 4081.510218773766, 4086.6730661547003, 4089.9161722143654, 4116.197341518375, 4116.444280022219, 4687.695299805502, 4691.812327506861, 4707.199776038082, 4738.620910327235, 4740.431148262231, 5480.91778623321, 5519.365272993577, 5588.580856435784, 5598.636088654224, 5635.2249041552595, 1330.5609148980814, 1359.9664255166144, 1402.382951501336, 1444.771943847742, 1450.8259008918997, 929.5352323838081, 930.6137952749533, 941.538646063424, 953.065995324572, 964.9035096490295, 4688.8124475021295, 4699.78850951708, 4704.388429504143, 4721.952780472151, 4755.904881902343, 4935.827246046443, 4941.209406494943, 4967.5777558907175, 4969.503049695074, 4995.950040499549, 4405.185040767468, 4702.31816386622, 4710.505396933273, 4719.19208071204, 4727.503612120678, 2598.2205338398403, 2609.7520735530093, 2618.5417551928335, 2624.2520881548667, 2631.67104111986, 5028.522290382408, 5053.1157047516535, 5077.933633936915, 5082.720450375265, 5093.898122037538, 4754.0, 5002.624803139779, 5005.474425185376, 5069.391672999263, 5094.745262736848, 5021.949780502148, 5025.79380948577, 5029.29589857413, 5055.772490237875, 5058.2821626105515, 4920.220075657242, 4928.383952005999, 4955.702657840541, 4978.850634480971, 4993.575546078624, 4579.9542004579525, 4875.463698993037, 4881.926771098365, 4938.950610493993, 4956.454789973172, 5048.697078175322, 5068.831980012492, 5097.61767867404, 5099.883548327402, 5132.820351287687, 3740.569834469071, 3778.2254637799733, 3786.4888240087234, 3791.75353602015, 3793.0, 4855.854324370274, 4969.925451118308, 4970.403551573834, 5004.174311238605, 5063.746812659426, 4829.396325459317, 4853.854384368475, 4864.589269111918, 4890.510948905081, 4917.852464426073, 4783.282507623884, 4815.759212039454, 4827.444843843003, 4871.585915197248, 4878.829240976549, 4650.697704649187, 4669.895139716072, 4791.688540244873, 4819.9274312046155, 4820.662553621353, 4801.68789028712, 4833.0, 4873.439554451284, 4874.5856602189215, 4882.5605695487575, 4739.881502962454, 4784.330193772805, 4791.904161916812, 4792.760361981817, 4811.49479304682], "value": 3965.7931782954056, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.3353480960493, 760.6501009535612, 766.6741634805206, 818.8935438393008, 835.9540225287615, 13985.720285594281, 14787.92606036971, 14788.6302842429, 14841.183734894577, 14894.776578351333, 15230.39078436864, 15390.076595404256, 15393.307301171446, 15404.306806193748, 15473.45842895496, 656.6355672601704, 674.6828990374516, 791.8851766493855, 803.7146812881431, 828.5235989306173, 821.4373154389233, 823.3989187892842, 824.11819353292, 824.5629816197425, 824.7484517222244, 866.2809867809734, 868.2099289646424, 870.9303255739526, 872.3849685971406, 872.4285592936636, 692.8891377379621, 708.638594316897, 836.4855613797531, 854.2354592639552, 866.1079088538784, 633.3792882974665, 633.996830015847, 634.5843472525503, 634.6667999300358, 634.8888944434706, 14885.33016014282, 14929.178895160761, 15035.398584056624, 15054.774178387333, 15067.5479735607, 14061.437542498288, 14128.000159278485, 14481.348339324755, 14646.0, 14693.485727999469, 13117.409716562777, 13138.343082845819, 13198.0, 13496.662583435394, 13674.384652690553, 4401.119776044804, 4439.156560253574, 4470.910581788378, 4492.797824097924, 4496.752678602676, 71432.0, 72192.27807721855, 84452.7773611317, 88701.55649221777, 89480.0, 11412.0, 11536.76926461466, 11611.76776464466, 11789.0, 12180.330081845406, 20289.289874854603, 22278.777212227993, 22300.107995680315, 22329.55340893173, 22340.0, 8707.956460217658, 8719.346049046348, 8843.646254149891, 8963.462192268486, 9013.278937684969, 4729.289462558107, 4734.455537613218, 4753.714777113384, 4806.831760888422, 4863.5622793948005, 3652.7808331500196, 3877.1082650989865, 3887.319719049155, 3915.686745060388, 3917.6278253565865, 646.4666650013758, 657.1785268414482, 657.9046038324512, 666.227176475296, 669.5848573884223, 646.6310522179404, 717.5766297884273, 723.9312265334785, 762.9122650895107, 778.0818634011807, 9274.721758347261, 10041.548130334153, 10193.796124077477, 10210.897891021143, 10325.535350909227, 708.922018577956, 738.4609235258291, 755.3541721827759, 758.3440324119597, 776.5496012312846, 4781.617470602413, 4877.975610121926, 5113.590912727047, 5120.539151476385, 5130.461301563357, 127254.0, 133194.33402832923, 136934.3153284247, 136979.0, 138100.0, 109104.0, 109592.0, 112151.0, 112474.43762781133, 115851.42074289575, 63874.0, 104919.4754026225, 105371.0, 105416.0, 107087.99999999844, 9278.768030799283, 9653.951730241304, 9705.5147242639, 9711.27165462579, 9748.122668959828, 48391.27413088736, 58511.70744146252, 59325.0, 59539.1069133972, 59753.7012314953, 72458.63770681113, 76074.6196269015, 77015.2298477008, 77746.0, 78067.219327806, 12697.492100315883, 12750.6174814752, 12853.421596028014, 12880.0, 13539.11995720275, 13421.261830599411, 14036.010118545464, 14194.0, 14264.358103885143, 14658.193799340712, 13430.731385372042, 13466.865331346562, 13605.65985850322, 13929.651758706113, 14609.342579584158, 13108.934455327662, 13695.589132326046, 14356.569302920929, 14370.928145359625, 14888.553343399715, 13068.934655326662, 13399.799003014768, 13635.86364136346, 14713.0, 15133.394664213749, 10678.57285708563, 10920.344779313267, 11128.610498632508, 11261.155413343868, 11514.021308188563, 10568.630097946534, 11129.109671226444, 11196.83204751913, 11196.832047519456, 11385.886141138482], "value": 7815.514040500885, "unit": "score"}], "name": "dromaeo_css", "value": 7189.7556643582075, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 111.0, 111.0, 112.0, 112.0, 111.0, 110.0, 110.0, 110.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 120.0, 119.0, 155.0, 119.0, 121.0, 122.0, 124.0, 151.0, 119.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 161.0, 161.0, 161.0, 161.0, 160.0, 162.0, 160.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 102.0, 75.0, 75.0, 77.0, 75.0, 118.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 112.0, 81.0, 80.0, 82.0, 100.0, 82.0, 83.0, 110.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 56.0, 50.0, 52.0, 51.0, 51.0, 52.0, 51.0, 51.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 61.0, 61.0, 60.0, 61.0, 60.0, 61.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 93.0, 82.0, 79.0, 85.0, 79.0, 78.0, 78.0, 78.0, 78.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 131.0, 124.0, 127.0, 132.0, 129.0, 124.0, 124.0, 161.0, 123.0], "value": 129.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 168.0, 167.0, 164.0, 164.0, 162.0, 163.0, 166.0, 166.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 64.0, 68.0, 64.0, 66.0, 65.0, 64.0, 65.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1484.6, "alertThreshold": 2.0}]} 00:42:40 INFO - Return code: 0 00:42:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:42:40 INFO - # TBPL SUCCESS # 00:42:40 INFO - Running post-action listener: _resource_record_post_action 00:42:40 INFO - [mozharness: 2016-09-19 07:42:40.736204Z] Finished run-tests step (success) 00:42:40 INFO - Running post-run listener: _resource_record_post_run 00:42:40 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395546624; Read time: 8; Write time: 251344 00:42:40 INFO - TinderboxPrint: CPU usage
13.2% 00:42:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 00:42:40 INFO - TinderboxPrint: I/O write bytes / time
395,546,624 / 251,344 00:42:40 INFO - TinderboxPrint: CPU idle
8,270.7 (86.8%) 00:42:40 INFO - TinderboxPrint: CPU user
1,201.2 (12.6%) 00:42:40 INFO - TinderboxPrint: Swap in / out
0 / 0 00:42:40 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 103862272; Read time: 0; Write time: 68028 00:42:40 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 291344384; Read time: 0; Write time: 183268 00:42:41 INFO - Running post-run listener: _upload_blobber_files 00:42:41 INFO - Blob upload gear active. 00:42:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:42:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 00:42:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:42:41 INFO - (blobuploader) - INFO - Open directory for files ... 00:42:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:42:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:42:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:42:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:42:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:42:42 INFO - (blobuploader) - INFO - Done attempting. 00:42:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:42:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:42:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:42:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:42:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:42:43 INFO - (blobuploader) - INFO - Done attempting. 00:42:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:42:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:42:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:42:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:42:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:42:47 INFO - (blobuploader) - INFO - Done attempting. 00:42:47 INFO - (blobuploader) - INFO - Iteration through files over. 00:42:47 INFO - Return code: 0 00:42:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:42:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:42:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e138fcc1c7311d6b03ee082e73d99d1a4b2b14ab9e708f4565077881a20178037faf4dc392b6457d8742a8d7cb690d52129e92646221f54a1821e73efbaf53f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5ded3baa1aeca39c268fcaf6d4eddd75a6d47a7c2cdc8fa649df75e03e4993ec0780dc8b6c16f2a79dd7d812cabf572ed9098a657a972bc2e2510e95aaae34c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54f8c90baa69a4e9d50f71c6527075828992e457e32c7714e03406b48a2efdb9ca908e666f1b9cc6c7ec3ad1ad124835b3fd0c2e5f87b7230704933044ccb26e"} 00:42:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:42:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:42:47 INFO - Contents: 00:42:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e138fcc1c7311d6b03ee082e73d99d1a4b2b14ab9e708f4565077881a20178037faf4dc392b6457d8742a8d7cb690d52129e92646221f54a1821e73efbaf53f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5ded3baa1aeca39c268fcaf6d4eddd75a6d47a7c2cdc8fa649df75e03e4993ec0780dc8b6c16f2a79dd7d812cabf572ed9098a657a972bc2e2510e95aaae34c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54f8c90baa69a4e9d50f71c6527075828992e457e32c7714e03406b48a2efdb9ca908e666f1b9cc6c7ec3ad1ad124835b3fd0c2e5f87b7230704933044ccb26e"} 00:42:47 INFO - Running post-run listener: copy_logs_to_upload_dir 00:42:47 INFO - Copying logs to upload dir... 00:42:47 INFO - mkdir: /builds/slave/test/build/upload/logs 00:42:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.948658 ========= master_lag: 0.87 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-19 00:42:48.285596) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 00:42:48.293133) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e138fcc1c7311d6b03ee082e73d99d1a4b2b14ab9e708f4565077881a20178037faf4dc392b6457d8742a8d7cb690d52129e92646221f54a1821e73efbaf53f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5ded3baa1aeca39c268fcaf6d4eddd75a6d47a7c2cdc8fa649df75e03e4993ec0780dc8b6c16f2a79dd7d812cabf572ed9098a657a972bc2e2510e95aaae34c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54f8c90baa69a4e9d50f71c6527075828992e457e32c7714e03406b48a2efdb9ca908e666f1b9cc6c7ec3ad1ad124835b3fd0c2e5f87b7230704933044ccb26e"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011184 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1474259425/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e138fcc1c7311d6b03ee082e73d99d1a4b2b14ab9e708f4565077881a20178037faf4dc392b6457d8742a8d7cb690d52129e92646221f54a1821e73efbaf53f7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5ded3baa1aeca39c268fcaf6d4eddd75a6d47a7c2cdc8fa649df75e03e4993ec0780dc8b6c16f2a79dd7d812cabf572ed9098a657a972bc2e2510e95aaae34c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/54f8c90baa69a4e9d50f71c6527075828992e457e32c7714e03406b48a2efdb9ca908e666f1b9cc6c7ec3ad1ad124835b3fd0c2e5f87b7230704933044ccb26e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 00:42:48.320805) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 00:42:48.321151) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1474261007.872837-569180397 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 00:42:48.370383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 00:42:48.373714) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 00:42:48.374040) ========= ========= Total master_lag: 1.03 =========