builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1452059064.7 results: success (0) buildid: 20160105163018 builduid: 817842ce86c34a829171082613e99cdf revision: dd6d447fc6e06da33a6b4a0ff44e6ce31ebc47dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.698261) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.698638) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.699138) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006286 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.724850) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.725159) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.772725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.773002) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014436 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.825443) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.825743) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.826106) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:24.826383) ========= 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-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-05 21:44:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-01-05 21:44:25 (43.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.473437 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:25.313340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:25.313680) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.108391 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:25.434380) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-05 21:44:25.434728) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dd6d447fc6e06da33a6b4a0ff44e6ce31ebc47dd --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dd6d447fc6e06da33a6b4a0ff44e6ce31ebc47dd --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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-05 21:44:25,441 truncating revision to first 12 chars 2016-01-05 21:44:25,441 Setting DEBUG logging. 2016-01-05 21:44:25,441 attempt 1/10 2016-01-05 21:44:25,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dd6d447fc6e0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-05 21:44:26,418 unpacking tar archive at: mozilla-central-dd6d447fc6e0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.299199 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-05 21:44:26.747157) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:26.747535) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:26.757393) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:26.757762) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-05 21:44:26.758272) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-01-05 21:44:26.758608) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:44:26 INFO - MultiFileLogger online at 20160105 21:44:26 in /builds/slave/test-pgo 21:44:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:44:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:44:26 INFO - {'append_to_log': False, 21:44:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:44:26 INFO - 'blob_upload_branch': 'Firefox', 21:44:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:44:26 INFO - 'branch': 'Firefox', 21:44:26 INFO - 'buildbot_json_path': 'buildprops.json', 21:44:26 INFO - 'config_files': ('talos/linux_config.py',), 21:44:26 INFO - 'default_actions': ('clobber', 21:44:26 INFO - 'read-buildbot-config', 21:44:26 INFO - 'download-and-extract', 21:44:26 INFO - 'populate-webroot', 21:44:26 INFO - 'create-virtualenv', 21:44:26 INFO - 'install', 21:44:26 INFO - 'run-tests'), 21:44:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:44:26 INFO - 'download_minidump_stackwalk': True, 21:44:26 INFO - 'download_symbols': 'ondemand', 21:44:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:44:26 INFO - 'tooltool.py': '/tools/tooltool.py', 21:44:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:44:26 INFO - '/tools/misc-python/virtualenv.py')}, 21:44:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:44:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:44:26 INFO - 'installer_path': 'installer.exe', 21:44:26 INFO - 'log_level': 'info', 21:44:26 INFO - 'log_name': 'talos', 21:44:26 INFO - 'log_to_console': True, 21:44:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:44:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:44:26 INFO - 'opt_config_files': (), 21:44:26 INFO - 'pip_index': False, 21:44:26 INFO - 'sps_profile': False, 21:44:26 INFO - 'sps_profile_interval': 0, 21:44:26 INFO - 'suite': 'dromaeojs', 21:44:26 INFO - 'system_bits': '32', 21:44:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:44:26 INFO - 'title': 'talos-linux64-ix-033', 21:44:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:44:26 INFO - 'use_talos_json': True, 21:44:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:44:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:44:26 INFO - 'work_dir': 'build'} 21:44:26 INFO - ##### 21:44:26 INFO - ##### Running clobber step. 21:44:26 INFO - ##### 21:44:26 INFO - Running pre-action listener: _resource_record_pre_action 21:44:26 INFO - Running main action method: clobber 21:44:26 INFO - rmtree: /builds/slave/test-pgo/build 21:44:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:44:27 INFO - Running post-action listener: _resource_record_post_action 21:44:27 INFO - ##### 21:44:27 INFO - ##### Running read-buildbot-config step. 21:44:27 INFO - ##### 21:44:27 INFO - Running pre-action listener: _resource_record_pre_action 21:44:27 INFO - Running main action method: read_buildbot_config 21:44:27 INFO - Using buildbot properties: 21:44:27 INFO - { 21:44:27 INFO - "project": "", 21:44:27 INFO - "product": "firefox", 21:44:27 INFO - "script_repo_revision": "production", 21:44:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:44:27 INFO - "repository": "", 21:44:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:44:27 INFO - "buildid": "20160105163018", 21:44:27 INFO - "pgo_build": "True", 21:44:27 INFO - "basedir": "/builds/slave/test-pgo", 21:44:27 INFO - "buildnumber": 631, 21:44:27 INFO - "slavename": "talos-linux64-ix-033", 21:44:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:44:27 INFO - "platform": "ubuntu64_hw", 21:44:27 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:44:27 INFO - "branch": "mozilla-central", 21:44:27 INFO - "repo_path": "mozilla-central", 21:44:27 INFO - "revision": "dd6d447fc6e06da33a6b4a0ff44e6ce31ebc47dd", 21:44:27 INFO - "stage_platform": "linux64-pgo", 21:44:27 INFO - "builduid": "817842ce86c34a829171082613e99cdf", 21:44:27 INFO - "slavebuilddir": "test" 21:44:27 INFO - } 21:44:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 21:44:27 INFO - Running post-action listener: _resource_record_post_action 21:44:27 INFO - ##### 21:44:27 INFO - ##### Running download-and-extract step. 21:44:27 INFO - ##### 21:44:27 INFO - Running pre-action listener: _resource_record_pre_action 21:44:27 INFO - Running main action method: download_and_extract 21:44:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:27 INFO - https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:44:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/test_packages.json 21:44:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/test_packages.json 21:44:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:44:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:44:31 INFO - Downloaded 1302 bytes. 21:44:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:44:31 INFO - Using the following test package requirements: 21:44:31 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:44:31 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:44:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:44:31 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:44:31 INFO - u'jsshell-linux-x86_64.zip'], 21:44:31 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:44:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:44:31 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:44:31 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:44:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:44:31 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:44:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:44:31 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:44:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:44:31 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:44:31 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:44:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:44:31 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:44:31 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:31 INFO - https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:44:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:44:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:44:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:44:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:44:32 INFO - Downloaded 24054207 bytes. 21:44:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:44:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:44:32 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:44:33 INFO - Return code: 0 21:44:33 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:44:33 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:33 INFO - https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:44:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:44:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:44:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:44:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:44:35 INFO - Downloaded 24054207 bytes. 21:44:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:44:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:44:35 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:44:37 INFO - Return code: 0 21:44:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:37 INFO - https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:44:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:44:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:44:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:44:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:44:39 INFO - Downloaded 11132285 bytes. 21:44:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 21:44:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:44:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 21:44:39 INFO - Return code: 0 21:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:39 INFO - https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:44:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:44:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:44:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:44:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:44:41 INFO - Downloaded 55047905 bytes. 21:44:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:44:41 INFO - mkdir: /builds/slave/test-pgo/properties 21:44:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:44:41 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:44:41 INFO - Contents: 21:44:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:44:41 INFO - Running post-action listener: _resource_record_post_action 21:44:41 INFO - Running post-action listener: set_extra_try_arguments 21:44:41 INFO - ##### 21:44:41 INFO - ##### Running populate-webroot step. 21:44:41 INFO - ##### 21:44:41 INFO - Running pre-action listener: _resource_record_pre_action 21:44:41 INFO - Running main action method: populate_webroot 21:44:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:44:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:44:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:44:41 INFO - '--tppagecycles', 21:44:41 INFO - '7'], 21:44:41 INFO - 'tests': ['tsvgm']}}, 21:44:41 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:44:41 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:44:41 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:44:41 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:44:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:44:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:41 INFO - 'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:44:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:41 INFO - 'tests': ['damp', 'tps']}, 21:44:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:41 INFO - 'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['damp', 'tps']}, 21:44:41 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:44:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:44:41 INFO - 'other': {'tests': ['a11yr', 21:44:41 INFO - 'ts_paint', 21:44:41 INFO - 'tpaint', 21:44:41 INFO - 'sessionrestore', 21:44:41 INFO - 'sessionrestore_no_auto_restore']}, 21:44:41 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['a11yr', 21:44:41 INFO - 'ts_paint', 21:44:41 INFO - 'tpaint', 21:44:41 INFO - 'sessionrestore', 21:44:41 INFO - 'sessionrestore_no_auto_restore']}, 21:44:41 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['a11yr', 21:44:41 INFO - 'ts_paint', 21:44:41 INFO - 'tpaint', 21:44:41 INFO - 'sessionrestore', 21:44:41 INFO - 'sessionrestore_no_auto_restore']}, 21:44:41 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['a11yr', 21:44:41 INFO - 'ts_paint', 21:44:41 INFO - 'tpaint', 21:44:41 INFO - 'sessionrestore', 21:44:41 INFO - 'sessionrestore_no_auto_restore']}, 21:44:41 INFO - 'other_l64': {'tests': ['a11yr', 21:44:41 INFO - 'ts_paint', 21:44:41 INFO - 'tpaint', 21:44:41 INFO - 'sessionrestore', 21:44:41 INFO - 'sessionrestore_no_auto_restore']}, 21:44:41 INFO - 'other_nol64': {'tests': ['a11yr', 21:44:41 INFO - 'ts_paint', 21:44:41 INFO - 'tpaint', 21:44:41 INFO - 'sessionrestore', 21:44:41 INFO - 'sessionrestore_no_auto_restore']}, 21:44:41 INFO - 'svgr': {'tests': ['tsvgx', 21:44:41 INFO - 'tsvgr_opacity', 21:44:41 INFO - 'tart', 21:44:41 INFO - 'tscrollx', 21:44:41 INFO - 'cart']}, 21:44:41 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['tsvgx', 21:44:41 INFO - 'tsvgr_opacity', 21:44:41 INFO - 'tart', 21:44:41 INFO - 'tscrollx', 21:44:41 INFO - 'cart']}, 21:44:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:41 INFO - 'tests': ['tp5o']}, 21:44:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:41 INFO - 'talos_options': ['--e10s'], 21:44:41 INFO - 'tests': ['tp5o']}, 21:44:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:41 INFO - 'talos_options': ['--xperf_path', 21:44:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:44:41 INFO - 'tests': ['tp5n']}, 21:44:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:44:41 INFO - 'talos_options': ['--e10s', 21:44:41 INFO - '--xperf_path', 21:44:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:44:41 INFO - 'tests': ['tp5n']}}, 21:44:41 INFO - 'talos.zip': {'path': '', 21:44:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:44:41 INFO - Running post-action listener: _resource_record_post_action 21:44:41 INFO - ##### 21:44:41 INFO - ##### Running create-virtualenv step. 21:44:41 INFO - ##### 21:44:41 INFO - Running pre-action listener: _resource_record_pre_action 21:44:41 INFO - Running main action method: create_virtualenv 21:44:41 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:44:41 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 21:44:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:44:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:44:41 INFO - Using real prefix '/usr' 21:44:41 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:44:42 INFO - Installing distribute.............................................................................................................................................................................................done. 21:44:44 INFO - Installing pip.................done. 21:44:44 INFO - Return code: 0 21:44:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:44:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 21:44:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 21:44:44 INFO - Using env: {'DISPLAY': ':0', 21:44:44 INFO - 'HOME': '/home/cltbld', 21:44:44 INFO - 'LANG': 'en_US.UTF-8', 21:44:44 INFO - 'LANGUAGE': 'en_US:en', 21:44:44 INFO - 'LOGNAME': 'cltbld', 21:44:44 INFO - 'MAIL': '/var/mail/cltbld', 21:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:44 INFO - 'MOZ_NO_REMOTE': '1', 21:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:44 INFO - 'NO_EM_RESTART': '1', 21:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:44:44 INFO - 'PWD': '/builds/slave/test-pgo', 21:44:44 INFO - 'SHELL': '/bin/bash', 21:44:44 INFO - 'SHLVL': '1', 21:44:44 INFO - 'TERM': 'linux', 21:44:44 INFO - 'TMOUT': '86400', 21:44:44 INFO - 'USER': 'cltbld', 21:44:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:44 INFO - '_': '/tools/buildbot/bin/python'} 21:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:44 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 21:44:44 INFO - Cleaning up... 21:44:44 INFO - Return code: 0 21:44:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:44:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test-pgo/build 21:44:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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>=0.7.1 21:44:44 INFO - Using env: {'DISPLAY': ':0', 21:44:44 INFO - 'HOME': '/home/cltbld', 21:44:44 INFO - 'LANG': 'en_US.UTF-8', 21:44:44 INFO - 'LANGUAGE': 'en_US:en', 21:44:44 INFO - 'LOGNAME': 'cltbld', 21:44:44 INFO - 'MAIL': '/var/mail/cltbld', 21:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:44 INFO - 'MOZ_NO_REMOTE': '1', 21:44:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:44 INFO - 'NO_EM_RESTART': '1', 21:44:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:44:44 INFO - 'PWD': '/builds/slave/test-pgo', 21:44:44 INFO - 'SHELL': '/bin/bash', 21:44:44 INFO - 'SHLVL': '1', 21:44:44 INFO - 'TERM': 'linux', 21:44:44 INFO - 'TMOUT': '86400', 21:44:44 INFO - 'USER': 'cltbld', 21:44:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:44 INFO - '_': '/tools/buildbot/bin/python'} 21:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:44 INFO - Downloading/unpacking psutil>=0.7.1 21:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:46 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:44:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:44:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:44:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:44:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:44:46 INFO - Installing collected packages: psutil 21:44:46 INFO - Running setup.py install for psutil 21:44:46 INFO - building 'psutil._psutil_linux' extension 21:44:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 21:44:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 21:44:46 INFO - building 'psutil._psutil_posix' extension 21:44:46 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 21:44:46 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 21:44:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:44:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:44:46 INFO - Successfully installed psutil 21:44:46 INFO - Cleaning up... 21:44:46 INFO - Return code: 0 21:44:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:46 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:44:46 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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.0.0'] in /builds/slave/test-pgo/build 21:44:46 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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.0.0 21:44:46 INFO - Using env: {'DISPLAY': ':0', 21:44:46 INFO - 'HOME': '/home/cltbld', 21:44:46 INFO - 'LANG': 'en_US.UTF-8', 21:44:46 INFO - 'LANGUAGE': 'en_US:en', 21:44:46 INFO - 'LOGNAME': 'cltbld', 21:44:46 INFO - 'MAIL': '/var/mail/cltbld', 21:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:46 INFO - 'MOZ_NO_REMOTE': '1', 21:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:46 INFO - 'NO_EM_RESTART': '1', 21:44:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:44:46 INFO - 'PWD': '/builds/slave/test-pgo', 21:44:46 INFO - 'SHELL': '/bin/bash', 21:44:46 INFO - 'SHLVL': '1', 21:44:46 INFO - 'TERM': 'linux', 21:44:46 INFO - 'TMOUT': '86400', 21:44:46 INFO - 'USER': 'cltbld', 21:44:46 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:46 INFO - '_': '/tools/buildbot/bin/python'} 21:44:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:44:47 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 21:44:47 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 21:44:47 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 21:44:47 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 21:44:47 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 21:44:47 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 21:44:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:44:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:44:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:44:48 INFO - Installing collected packages: mozsystemmonitor 21:44:48 INFO - Running setup.py install for mozsystemmonitor 21:44:48 INFO - Successfully installed mozsystemmonitor 21:44:48 INFO - Cleaning up... 21:44:49 INFO - Return code: 0 21:44:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:44:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 21:44:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 21:44:49 INFO - Using env: {'DISPLAY': ':0', 21:44:49 INFO - 'HOME': '/home/cltbld', 21:44:49 INFO - 'LANG': 'en_US.UTF-8', 21:44:49 INFO - 'LANGUAGE': 'en_US:en', 21:44:49 INFO - 'LOGNAME': 'cltbld', 21:44:49 INFO - 'MAIL': '/var/mail/cltbld', 21:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:49 INFO - 'MOZ_NO_REMOTE': '1', 21:44:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:49 INFO - 'NO_EM_RESTART': '1', 21:44:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:44:49 INFO - 'PWD': '/builds/slave/test-pgo', 21:44:49 INFO - 'SHELL': '/bin/bash', 21:44:49 INFO - 'SHLVL': '1', 21:44:49 INFO - 'TERM': 'linux', 21:44:49 INFO - 'TMOUT': '86400', 21:44:49 INFO - 'USER': 'cltbld', 21:44:49 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:49 INFO - '_': '/tools/buildbot/bin/python'} 21:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:49 INFO - Downloading/unpacking blobuploader==1.2.4 21:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:50 INFO - Downloading blobuploader-1.2.4.tar.gz 21:44:51 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:44:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:44:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:44:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:44:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:44:54 INFO - Downloading docopt-0.6.1.tar.gz 21:44:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:44:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:44:54 INFO - Installing collected packages: blobuploader, requests, docopt 21:44:54 INFO - Running setup.py install for blobuploader 21:44:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:44:55 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:44:55 INFO - Running setup.py install for requests 21:44:55 INFO - Running setup.py install for docopt 21:44:55 INFO - Successfully installed blobuploader requests docopt 21:44:55 INFO - Cleaning up... 21:44:55 INFO - Return code: 0 21:44:55 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:44:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 21:44:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 21:44:55 INFO - Using env: {'DISPLAY': ':0', 21:44:55 INFO - 'HOME': '/home/cltbld', 21:44:55 INFO - 'LANG': 'en_US.UTF-8', 21:44:55 INFO - 'LANGUAGE': 'en_US:en', 21:44:55 INFO - 'LOGNAME': 'cltbld', 21:44:55 INFO - 'MAIL': '/var/mail/cltbld', 21:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:55 INFO - 'MOZ_NO_REMOTE': '1', 21:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:55 INFO - 'NO_EM_RESTART': '1', 21:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:44:55 INFO - 'PWD': '/builds/slave/test-pgo', 21:44:55 INFO - 'SHELL': '/bin/bash', 21:44:55 INFO - 'SHLVL': '1', 21:44:55 INFO - 'TERM': 'linux', 21:44:55 INFO - 'TMOUT': '86400', 21:44:55 INFO - 'USER': 'cltbld', 21:44:55 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:55 INFO - '_': '/tools/buildbot/bin/python'} 21:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:44:55 INFO - Running setup.py (path:/tmp/pip-pZJDVJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:44:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:44:55 INFO - Running setup.py (path:/tmp/pip-eip5wT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:44:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:44:55 INFO - Running setup.py (path:/tmp/pip-6ky4b1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:44:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:44:55 INFO - Running setup.py (path:/tmp/pip-zhVW6e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:44:56 INFO - Running setup.py (path:/tmp/pip-Va0Ddy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:44:56 INFO - Running setup.py (path:/tmp/pip-7bCIvP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:44:56 INFO - Running setup.py (path:/tmp/pip-qy9Mxg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:44:56 INFO - Running setup.py (path:/tmp/pip-0FK8mj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:44:56 INFO - Running setup.py (path:/tmp/pip-dSAFjP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:44:56 INFO - Running setup.py (path:/tmp/pip-c9YKzW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:44:56 INFO - Running setup.py (path:/tmp/pip-Vpf5aZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:44:56 INFO - Running setup.py (path:/tmp/pip-OyAX16-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:44:56 INFO - Running setup.py (path:/tmp/pip-Jjv5gp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:44:56 INFO - Running setup.py (path:/tmp/pip-yVYjut-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:44:56 INFO - Running setup.py (path:/tmp/pip-n0Hy3w-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:44:56 INFO - Running setup.py (path:/tmp/pip-81IwQl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:44:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:44:56 INFO - Running setup.py (path:/tmp/pip-tEgubH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:44:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:44:56 INFO - Running setup.py install for manifestparser 21:44:57 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Running setup.py install for mozcrash 21:44:57 INFO - Running setup.py install for mozdebug 21:44:57 INFO - Running setup.py install for mozdevice 21:44:57 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Running setup.py install for mozfile 21:44:57 INFO - Running setup.py install for mozhttpd 21:44:57 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Running setup.py install for mozinfo 21:44:57 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Running setup.py install for mozInstall 21:44:57 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:44:57 INFO - Running setup.py install for mozleak 21:44:57 INFO - Running setup.py install for mozlog 21:44:58 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:44:58 INFO - Running setup.py install for moznetwork 21:44:58 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:44:58 INFO - Running setup.py install for mozprocess 21:44:58 INFO - Running setup.py install for mozprofile 21:44:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:44:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:44:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:44:58 INFO - Running setup.py install for mozrunner 21:44:58 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:44:58 INFO - Running setup.py install for mozscreenshot 21:44:58 INFO - Running setup.py install for moztest 21:44:58 INFO - Running setup.py install for mozversion 21:44:58 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:44:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:44:58 INFO - Cleaning up... 21:44:58 INFO - Return code: 0 21:44:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:44:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:44:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:44:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:44:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/config 21:44:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 21:44:58 INFO - Using env: {'DISPLAY': ':0', 21:44:58 INFO - 'HOME': '/home/cltbld', 21:44:58 INFO - 'LANG': 'en_US.UTF-8', 21:44:58 INFO - 'LANGUAGE': 'en_US:en', 21:44:58 INFO - 'LOGNAME': 'cltbld', 21:44:58 INFO - 'MAIL': '/var/mail/cltbld', 21:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:44:58 INFO - 'MOZ_NO_REMOTE': '1', 21:44:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:44:58 INFO - 'NO_EM_RESTART': '1', 21:44:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:44:58 INFO - 'PWD': '/builds/slave/test-pgo', 21:44:58 INFO - 'SHELL': '/bin/bash', 21:44:58 INFO - 'SHLVL': '1', 21:44:58 INFO - 'TERM': 'linux', 21:44:58 INFO - 'TMOUT': '86400', 21:44:58 INFO - 'USER': 'cltbld', 21:44:58 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:44:58 INFO - '_': '/tools/buildbot/bin/python'} 21:44:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:44:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:44:59 INFO - Running setup.py (path:/tmp/pip-TTnbo3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:44:59 INFO - Running setup.py (path:/tmp/pip-EuRy98-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:44:59 INFO - Running setup.py (path:/tmp/pip-4IljDh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:44:59 INFO - Running setup.py (path:/tmp/pip-facbc0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:44:59 INFO - Running setup.py (path:/tmp/pip-bztHRM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:44:59 INFO - Running setup.py (path:/tmp/pip-6GTeLB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:44:59 INFO - Running setup.py (path:/tmp/pip-yd73VW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:44:59 INFO - Running setup.py (path:/tmp/pip-PJKrMe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:44:59 INFO - Running setup.py (path:/tmp/pip-Oiq3vN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:44:59 INFO - Running setup.py (path:/tmp/pip-H_iYAO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:44:59 INFO - Running setup.py (path:/tmp/pip-avYkHK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:44:59 INFO - Running setup.py (path:/tmp/pip-o3oWCv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:44:59 INFO - Running setup.py (path:/tmp/pip-JZymDF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 21:44:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:44:59 INFO - Running setup.py (path:/tmp/pip-vb_KDz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 21:45:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:45:00 INFO - Running setup.py (path:/tmp/pip-4AC07a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 21:45:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:45:00 INFO - Running setup.py (path:/tmp/pip-yu29ms-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 21:45:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:45:00 INFO - Running setup.py (path:/tmp/pip-tFD3HI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:45:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:45:00 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 21:45:00 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 21:45:00 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 21:45:00 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 21:45:00 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 21:45:00 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 21:45:01 INFO - Downloading blessings-1.5.1.tar.gz 21:45:01 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 21:45:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:45:02 INFO - Installing collected packages: blessings 21:45:02 INFO - Running setup.py install for blessings 21:45:02 INFO - Successfully installed blessings 21:45:02 INFO - Cleaning up... 21:45:02 INFO - Return code: 0 21:45:02 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:45:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:45:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:45:02 INFO - Reading from file tmpfile_stdout 21:45:02 INFO - Current package versions: 21:45:02 INFO - argparse == 1.2.1 21:45:02 INFO - blessings == 1.5.1 21:45:02 INFO - blobuploader == 1.2.4 21:45:02 INFO - docopt == 0.6.1 21:45:02 INFO - manifestparser == 1.1 21:45:02 INFO - mozInstall == 1.12 21:45:02 INFO - mozcrash == 0.16 21:45:02 INFO - mozdebug == 0.1 21:45:02 INFO - mozdevice == 0.47 21:45:02 INFO - mozfile == 1.2 21:45:02 INFO - mozhttpd == 0.7 21:45:02 INFO - mozinfo == 0.9 21:45:02 INFO - mozleak == 0.1 21:45:02 INFO - mozlog == 3.1 21:45:02 INFO - moznetwork == 0.27 21:45:02 INFO - mozprocess == 0.22 21:45:02 INFO - mozprofile == 0.28 21:45:02 INFO - mozrunner == 6.11 21:45:02 INFO - mozscreenshot == 0.1 21:45:02 INFO - mozsystemmonitor == 0.0 21:45:02 INFO - moztest == 0.7 21:45:02 INFO - mozversion == 1.4 21:45:02 INFO - psutil == 3.1.1 21:45:02 INFO - requests == 1.2.3 21:45:02 INFO - wsgiref == 0.1.2 21:45:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:45:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/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-pgo/build/tests/talos 21:45:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/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 21:45:02 INFO - Using env: {'DISPLAY': ':0', 21:45:02 INFO - 'HOME': '/home/cltbld', 21:45:02 INFO - 'LANG': 'en_US.UTF-8', 21:45:02 INFO - 'LANGUAGE': 'en_US:en', 21:45:02 INFO - 'LOGNAME': 'cltbld', 21:45:02 INFO - 'MAIL': '/var/mail/cltbld', 21:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:02 INFO - 'MOZ_NO_REMOTE': '1', 21:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:02 INFO - 'NO_EM_RESTART': '1', 21:45:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:02 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:02 INFO - 'SHELL': '/bin/bash', 21:45:02 INFO - 'SHLVL': '1', 21:45:02 INFO - 'TERM': 'linux', 21:45:02 INFO - 'TMOUT': '86400', 21:45:02 INFO - 'USER': 'cltbld', 21:45:02 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:02 INFO - '_': '/tools/buildbot/bin/python'} 21:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 21:45:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 21:45:02 INFO - Cleaning up... 21:45:02 INFO - Return code: 0 21:45:02 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:45:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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-pgo/build 21:45:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 21:45:02 INFO - Using env: {'DISPLAY': ':0', 21:45:02 INFO - 'HOME': '/home/cltbld', 21:45:02 INFO - 'LANG': 'en_US.UTF-8', 21:45:02 INFO - 'LANGUAGE': 'en_US:en', 21:45:02 INFO - 'LOGNAME': 'cltbld', 21:45:02 INFO - 'MAIL': '/var/mail/cltbld', 21:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:02 INFO - 'MOZ_NO_REMOTE': '1', 21:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:02 INFO - 'NO_EM_RESTART': '1', 21:45:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:02 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:02 INFO - 'SHELL': '/bin/bash', 21:45:02 INFO - 'SHLVL': '1', 21:45:02 INFO - 'TERM': 'linux', 21:45:02 INFO - 'TMOUT': '86400', 21:45:02 INFO - 'USER': 'cltbld', 21:45:02 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:02 INFO - '_': '/tools/buildbot/bin/python'} 21:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:02 INFO - Downloading/unpacking jsonschema 21:45:02 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 21:45:02 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 21:45:02 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 21:45:02 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 21:45:02 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 21:45:02 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 21:45:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:45:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 21:45:04 INFO - Downloading/unpacking functools32 (from jsonschema) 21:45:04 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 21:45:04 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 21:45:04 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 21:45:04 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 21:45:04 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 21:45:04 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 21:45:04 INFO - Downloading functools32-3.2.3-2.tar.gz 21:45:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 21:45:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:45:04 INFO - warning: no files found matching '*.txt' 21:45:04 INFO - no previously-included directories found matching 'build' 21:45:04 INFO - no previously-included directories found matching 'dist' 21:45:04 INFO - no previously-included directories found matching '.git*' 21:45:04 INFO - Installing collected packages: jsonschema, functools32 21:45:04 INFO - Running setup.py install for functools32 21:45:04 INFO - warning: no files found matching '*.txt' 21:45:04 INFO - no previously-included directories found matching 'build' 21:45:04 INFO - no previously-included directories found matching 'dist' 21:45:04 INFO - no previously-included directories found matching '.git*' 21:45:04 INFO - Successfully installed jsonschema functools32 21:45:04 INFO - Cleaning up... 21:45:04 INFO - Return code: 0 21:45:04 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 21:45:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:45:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1b63ab1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1589480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', '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-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/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 21:45:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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', 'flake8'] in /builds/slave/test-pgo/build 21:45:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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 flake8 21:45:04 INFO - Using env: {'DISPLAY': ':0', 21:45:04 INFO - 'HOME': '/home/cltbld', 21:45:04 INFO - 'LANG': 'en_US.UTF-8', 21:45:04 INFO - 'LANGUAGE': 'en_US:en', 21:45:04 INFO - 'LOGNAME': 'cltbld', 21:45:04 INFO - 'MAIL': '/var/mail/cltbld', 21:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:04 INFO - 'MOZ_NO_REMOTE': '1', 21:45:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:04 INFO - 'NO_EM_RESTART': '1', 21:45:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:04 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:04 INFO - 'SHELL': '/bin/bash', 21:45:04 INFO - 'SHLVL': '1', 21:45:04 INFO - 'TERM': 'linux', 21:45:04 INFO - 'TMOUT': '86400', 21:45:04 INFO - 'USER': 'cltbld', 21:45:04 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:04 INFO - '_': '/tools/buildbot/bin/python'} 21:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:45:05 INFO - Downloading/unpacking flake8 21:45:05 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 21:45:05 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 21:45:05 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 21:45:05 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 21:45:05 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 21:45:05 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 21:45:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:45:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 21:45:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:45:06 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 21:45:06 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 21:45:06 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 21:45:06 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 21:45:06 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 21:45:06 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 21:45:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:45:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 21:45:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:45:07 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 21:45:07 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 21:45:07 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 21:45:07 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 21:45:07 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 21:45:07 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 21:45:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 21:45:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:45:07 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 21:45:07 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 21:45:07 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 21:45:07 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 21:45:07 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 21:45:07 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 21:45:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 21:45:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 21:45:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:45:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:45:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:45:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:45:07 INFO - no previously-included directories found matching 'docs/_build' 21:45:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:45:07 INFO - Running setup.py install for pep8 21:45:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:45:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:45:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:45:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:45:07 INFO - no previously-included directories found matching 'docs/_build' 21:45:07 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 21:45:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:45:07 INFO - Cleaning up... 21:45:07 INFO - Return code: 0 21:45:07 INFO - Running post-action listener: _resource_record_post_action 21:45:07 INFO - Running post-action listener: _start_resource_monitoring 21:45:07 INFO - Starting resource monitoring. 21:45:07 INFO - ##### 21:45:07 INFO - ##### Running install step. 21:45:07 INFO - ##### 21:45:07 INFO - Running pre-action listener: _resource_record_pre_action 21:45:07 INFO - Running main action method: install 21:45:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:45:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:45:08 INFO - Reading from file tmpfile_stdout 21:45:08 INFO - Detecting whether we're running mozinstall >=1.0... 21:45:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:45:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:45:08 INFO - Reading from file tmpfile_stdout 21:45:08 INFO - Output received: 21:45:08 INFO - Usage: mozinstall [options] installer 21:45:08 INFO - Options: 21:45:08 INFO - -h, --help show this help message and exit 21:45:08 INFO - -d DEST, --destination=DEST 21:45:08 INFO - Directory to install application into. [default: 21:45:08 INFO - "/builds/slave/test-pgo"] 21:45:08 INFO - --app=APP Application being installed. [default: firefox] 21:45:08 INFO - mkdir: /builds/slave/test-pgo/build/application 21:45:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 21:45:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 21:45:23 INFO - Reading from file tmpfile_stdout 21:45:23 INFO - Output received: 21:45:23 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:45:23 INFO - Running post-action listener: _resource_record_post_action 21:45:23 INFO - ##### 21:45:23 INFO - ##### Running run-tests step. 21:45:23 INFO - ##### 21:45:23 INFO - Running pre-action listener: _resource_record_pre_action 21:45:23 INFO - Running main action method: run_tests 21:45:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:45:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:45:23 INFO - Python 2.7.3 21:45:23 INFO - Return code: 0 21:45:23 INFO - grabbing minidump binary from tooltool 21:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:45:23 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-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1618660>, '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 0x1619560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1619a10>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:45:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 21:45:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:45:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:45:24 INFO - Return code: 0 21:45:24 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:45:24 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:45:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:45:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:45:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:45:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 21:45:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 21:45:24 INFO - Return code: 0 21:45:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 21:45:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 21:45:24 INFO - Using env: {'DISPLAY': ':0', 21:45:24 INFO - 'HOME': '/home/cltbld', 21:45:24 INFO - 'LANG': 'en_US.UTF-8', 21:45:24 INFO - 'LANGUAGE': 'en_US:en', 21:45:24 INFO - 'LOGNAME': 'cltbld', 21:45:24 INFO - 'MAIL': '/var/mail/cltbld', 21:45:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:45:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:45:24 INFO - 'MOZ_NO_REMOTE': '1', 21:45:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:45:24 INFO - 'NO_EM_RESTART': '1', 21:45:24 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:45:24 INFO - 'PWD': '/builds/slave/test-pgo', 21:45:24 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:45:24 INFO - 'SHELL': '/bin/bash', 21:45:24 INFO - 'SHLVL': '1', 21:45:24 INFO - 'TERM': 'linux', 21:45:24 INFO - 'TMOUT': '86400', 21:45:24 INFO - 'USER': 'cltbld', 21:45:24 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226', 21:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:45:24 INFO - '_': '/tools/buildbot/bin/python'} 21:45:24 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:45:25 INFO - mozversion application_buildid: 20160105163018 21:45:25 INFO - mozversion application_changeset: dd6d447fc6e06da33a6b4a0ff44e6ce31ebc47dd 21:45:25 INFO - mozversion application_display_name: Nightly 21:45:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:45:25 INFO - mozversion application_name: Firefox 21:45:25 INFO - mozversion application_remotingname: firefox 21:45:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:45:25 INFO - mozversion application_vendor: Mozilla 21:45:25 INFO - mozversion application_version: 46.0a1 21:45:25 INFO - mozversion platform_buildid: 20160105163018 21:45:25 INFO - mozversion platform_changeset: dd6d447fc6e06da33a6b4a0ff44e6ce31ebc47dd 21:45:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:45:25 INFO - mozversion platform_version: 46.0a1 21:45:25 INFO - using testdate: 1452059125 21:45:25 INFO - actual date: 1452059125 21:45:25 INFO - starting webserver on 'localhost:44546' 21:45:25 INFO - SUITE-START | Running 3 tests 21:45:25 INFO - TEST-START | dromaeo_css 21:45:25 INFO - operating with platform_type : linux_ 21:45:25 INFO - Initialising browser for dromaeo_css test... 21:45:25 INFO - TEST-INFO | started process 6347 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpygr7y4/profile http://localhost:44546/getInfo.html) 21:45:30 INFO - PROCESS | 6347 | __metrics Screen width/height:1600/1200 21:45:30 INFO - PROCESS | 6347 | colorDepth:24 21:45:30 INFO - PROCESS | 6347 | Browser inner width/height: 1024/697 21:45:30 INFO - PROCESS | 6347 | __metrics 21:45:30 INFO - PROCESS | 6347 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 21:45:30 INFO - PROCESS | 6347 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:45:31 INFO - TEST-INFO | 6347: exit 0 21:45:31 INFO - Browser initialized. 21:45:31 INFO - Running cycle 1/1 for dromaeo_css test... 21:45:31 INFO - TEST-INFO | started process 6440 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpygr7y4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:45:43 INFO - PROCESS | 6440 | RSS: Main: 149741568 21:45:43 INFO - PROCESS | 6440 | 21:46:34 INFO - PROCESS | 6440 | 1452059194652 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] 21:48:43 INFO - PROCESS | 6440 | Cycle 1(1): loaded http://localhost:44546/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44546/tests/dromaeo/cssquery-ext.html) 21:48:44 INFO - PROCESS | 6440 | RSS: Main: 238071808 21:48:44 INFO - PROCESS | 6440 | 21:51:46 INFO - PROCESS | 6440 | Cycle 1(1): loaded http://localhost:44546/tests/dromaeo/cssquery-ext.html (next: http://localhost:44546/tests/dromaeo/cssquery-jquery.html) 21:51:47 INFO - PROCESS | 6440 | RSS: Main: 227385344 21:51:47 INFO - PROCESS | 6440 | 21:54:49 INFO - PROCESS | 6440 | Cycle 1(1): loaded http://localhost:44546/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44546/tests/dromaeo/cssquery-mootools.html) 21:54:49 INFO - PROCESS | 6440 | RSS: Main: 232759296 21:54:49 INFO - PROCESS | 6440 | 21:57:51 INFO - PROCESS | 6440 | Cycle 1(1): loaded http://localhost:44546/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44546/tests/dromaeo/cssquery-prototype.html) 21:57:52 INFO - PROCESS | 6440 | RSS: Main: 210239488 21:57:52 INFO - PROCESS | 6440 | 22:00:54 INFO - PROCESS | 6440 | Cycle 1(1): loaded http://localhost:44546/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44546/tests/dromaeo/cssquery-yui.html) 22:00:55 INFO - PROCESS | 6440 | RSS: Main: 216985600 22:00:55 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | Cycle 1(1): loaded http://localhost:44546/tests/dromaeo/cssquery-yui.html (next: http://localhost:44546/tests/dromaeo/cssquery-dojo.html) 22:03:58 INFO - PROCESS | 6440 | RSS: Main: 260063232 22:03:58 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | __start_tp_report 22:03:58 INFO - PROCESS | 6440 | _x_x_mozilla_page_load 22:03:58 INFO - PROCESS | 6440 | _x_x_mozilla_page_load_details 22:03:58 INFO - PROCESS | 6440 | |i|pagename|runs| 22:03:58 INFO - PROCESS | 6440 | |0;dojo.html;3077.3691393264385;3188.171075520364;3241.141097609134;3241.335526217126;3273.7872038317514;4966.180580204271;5093.617978651598;5166.457521960197;5201.557867581256;5242.659227150232;4991.500849915006;5028.371453568304;5030.723310217936;5037.1688671369275;5060.772265248063;4680.485146633859;4703.670743047971;4726.787294571753;4800.039992001613;4840.225563909775;532.5018645980937;4545.795439205243;4716.858494245161;4738.336632871384;4793.376861008064;4652.209124448852;4672.813087476497;4723.267893476483;4748.76256187191;4758.690685105492;4684.3910291662205;4688.929666055012;4694.882627934295;4700.858974230779;4731.550502702238;4584.862454126382;4663.347131401607;4688.249880019214;4694.50707675696;4747.264174053027;4853.635977301681;4896.902061958741;4910.558049775537;4929;4963.503649635044;5472.151816468454;5504.724763761836;5506.862328441781;5551.333839939192;5552.028395030853;5349.973250133763;5490.3411590609385;5490.835274941758;5540.9168862467095;5543;5166.948330516647;5181.5077567631015;5184.040952423814;5203.271541984125;5210.452902445264;5552.7223638817895;5595.720213989284;5607.8317650469735;5609.354924183689;5610.49505544503;5317.255584218213;5416.891662166735;5419.891602167934;5424.186372044225;5426.83719488408;5451.48210919962;5468.234447177399;5540.806071787468;5540.833774986757;5577.3028371453565;5491.066518691832;5495.313085864267;5496.835094947078;5496.917546236809;5500.28496295487;5394.946050539523;5497.47773961473;5498.532624726863;5504.669719816824;5553.194786755978;4273.465816772903;4339.327404252346;4340.023494713664;4377.934330985037;4378.014946636981;3827.78947157909;3830.042489377656;3839.7696138231236;3855.575886652466;3867;4965.280034394992;5056.469070747518;5071.771770270347;5085.923711144335;5102.08162530748;5518.19986102013;5545.833624991176;5546.611737178358;5551.167324901297;5573.832785016456;5134.409542902539;5138.126518491866;5141.665791723526;5142.51146141116;5184.766685499161;4674.602658773974;4757.857264282077;4762.142814293391;4767.570918617341;4856;5692.37383887772;5698.971505142447;5711.914321285184;5714.571407144397;5718.170865224519;5470.53500452466;5473.684210526316;5483.287172667688;5487;5487.506124448819;5569.610127291051;5575.916361254665;5620.3536593290655;5660.29246344207;5683.744231509675;5432.402435732145;5472.34331880161;5480.6437581557075;5482.945170548403;5486.149646804752;5515.007298686275;5526.22632831412;5528.364238112668;5528.977139229255;5583.664980101207;5270.973645131749;5378.381486129145;5383.757730902041;5445.646033007842;5499.037668407933;5420.105682562333;5463.72681365938;5463.972680136574;5466.070767969375;5471.206675032177;5221;5361.758720857492;5398.13629819227;5444.1017232156255;5448.645838020516;5295.973520132375;5359.464053594609;5400.2439658447065;5417.756200971045;5433.049216387196;5195.038917800219;5202.063628546898;5204.609654275869;5205.6616319939085;5214.713190774392 22:03:58 INFO - PROCESS | 6440 | |1;ext.html;511.15148852904184;535.8446050645313;540.42174872886;561.6994907724363;584.5907864494852;28065.43869122616;38088;38897.61102388975;38914.610853891456;39047.99999999996;42039.36940945888;42836;42864.78567607165;42926.35610465846;42992;7280.417566594686;7396.334329910307;8528.488290702546;8731;9800.362976406546;9898.35660682057;10022.24833137514;10025.59897604095;10034.448105354202;10038.397696138256;10033.749156271078;10184.949075254613;10191.54138063789;10196.592136314539;10211.33626314291;7527.698892044312;7604.847903041936;10082.9495852521;10316.123129533999;10321.690349289533;9901;10040.748981275454;10065.043820837009;10069.69790906274;10069.748256293578;37366.62633373659;37798.622013779786;37810;37833.43249851254;37920;3001.4630396508455;3058.464768665497;3078.8614512346776;3299.8350082496017;3633.9636603634153;29550;29666.25834354137;29690.85154574235;29920.251993700113;30342;3632.963670363289;3755.324041672498;3755.4930084438524;3861.0347413146715;4005.8783540608674;262620;263515;265462;265999;267237.93144822743;54018.459815401344;55647.443525564224;60232.39767602355;60683;60749.39250607526;65293;66086.66956665186;66338;66473.66763166153;66488.66755666287;13489.392977316043;13610.251436170907;13617.182969021891;13622.250776207205;13624.318784060757;11060.281081729661;11126.443677816238;11127.833082503768;11138.832917506244;11142;19271.662284400165;20357.389278321672;20390.184392624127;20403.79596204019;20568.79431205669;7266.92733072673;7421.406287496988;7431.699452595774;7478.70085196586;7512.436567257478;905.968291109808;924;962.2975228083538;979.4906648542718;1013.8580598716186;28596.571051434246;28870.278243044093;28884.42231155407;29010.709892901225;29263.561046584317;990.2820455169945;1025.6922923123182;1083.5719890643152;1087.521490544174;1103.4151899493281;14645.267736613127;14915.701685966436;14975.251237438084;15176.24118794078;15264.694706105816;312894;317997;319395;319882;320475;52191.47808521942;53039.46960530423;53055.469445305826;53136;53137;1290.6321698315935;1303.1985329022773;1319.4788058716945;1320.1880843281506;1337.5519201067557;25817.483650327264;27374.72625273722;28627.28431789222;29524.557131642614;29860;47167;47847.28229076497;47849.521504784505;47851;47853.28220076771;227546;229601;231289;231889;232262;27521.449571008867;27805.748741307096;29072.709272907847;31204;31346;37891.05272368213;38223.80888095542;38906.80546597249;39464.60535394609;42099.368509472995;34453;34560.65439345675;34853.65146348504;35203.82398087993;35804.462933056544;29903.700962990963;34554.65445345615;35361.469577956865;36063.27873442569;36215.27569448649;32242.35515289728;32386;32649.510257345682;32731.672683272864;33389.83305083459;30118.3976320468;31614.209644759067;32019.839900800347;32115.67884321127;32391.676083238865;23571.528569428174;24534.263972080866;24616.6307505384;25109.497810044064;25902.740972590032 22:03:58 INFO - PROCESS | 6440 | |2;jquery.html;348.6147806673627;351.3552630922259;356.3016487684138;356.8037579331367;358.6341931230144;14008.789868151986;14454.421823127062;16047.51857444277;16217.270222839987;16839.831601683996;15576.532704018897;15816.604584885356;16467.34130634773;16497.752533712002;16512.339506419758;5007.799688012475;5345.946540534584;5350.759215835296;5351.411344752094;5351.919721204165;4056.7565946043183;4116.711830171889;5237.869053273661;5643.6049476536655;5766.711664416784;5694.117411801178;5699.2306038684865;5724.198612194296;5747.109198074284;5747.310322761275;5582.106862901936;5805.854853628651;5806.622569532967;5806.912896306559;5810.24466819315;5090.516400941905;5102.234664800272;5113.488651134857;5154.149565321717;5155.561777248939;20483;20641;20724.378268651966;20759.273425430038;20869.269575564933;2129.3718353085724;2130.286354071388;2167.631502644554;2213.3249358945513;2253.9123533642487;12916.741665166644;13011.479540818356;13131.671708207372;13352.532661356903;13445.058845880792;1782.3672596228282;1782.8395444410066;1786.285485805675;1802.774653168354;1844.7970723220435;57510.7124464375;59260;59425.10862337112;59601.403985960016;59778.402215977716;9581;9863.80272394548;9865.161461275728;9885.357451765612;9939.353941993748;12806.103572750004;12916.870831291755;12926.935365323301;12946.352682366032;12947.482100716054;7389.965404843326;7399.48203625741;7442.7395041173295;7444.478886478003;7541.962290188514;7136.179339375934;7154.964225178841;7184;7205.315495027964;7214.567125972353;5850.64896106235;5892.292924849045;5900.704964751745;5921.111833224964;5961.254843144607;1672.481530725479;1689.4931520543787;1715.5025042737716;1743.755874177616;1763.5414792153877;895.4627223665879;922.5202894494826;987.3828856964398;1073.7906108308962;1077.4881931082673;14950.177740224392;15506.844931550766;16418;16592.917035414746;16755.664886702198;933.5892207428709;940.8024314893812;945.3760518058049;985.9309848310693;986.0977205856561;9662.227021838236;9849;9963.402195868272;10047.59809607622;10096.545655445523;506893;508186;515321;527215;533111;67845.3215467849;68627.65686171537;69255.30744692488;69887.65056174788;70479.29520704727;1128.323006196292;1130.0902773267435;1137.1300954769608;1147.4148184426003;1150.8791576884473;15143.772843407138;15176.468823591122;15441.845581544041;15625.68748625044;15850.603734906259;34145;34262;34429.48355774715;34475;34496.827515862264;43972;44240.55759442493;44535.33197002112;44961.62737862859;46153;9662.888767791794;9739.853902191331;9781.266405019795;9797.21622270231;9799.902000979899;12980.598589261017;16519.752203716715;17432.82567174312;17537;17766.822331777035;11879.76240475203;13219.9424046078;13939.581812545639;14056.78914816299;14179.858201417854;14314.284285785878;14347;14512.056716313828;14763.409463621334;15194.696106078038;14824.40702371893;15177.178738551445;15287.235638218268;15463.768043479582;15792.684146317239;9342.486163261086;9444.433333999981;9480.383775054599;9760.365576237522;9795;10229.181665466596;10256.38461692301;12465.813012804996;14215.857841421453;14244.572662820132 22:03:58 INFO - PROCESS | 6440 | |3;mootools.html;1319;1434.526606219947;1436.102435977514;1565.4520917678815;1756.8243175682442;6873.828154296145;7623.961880190598;7718.729844455445;7726.188750181221;7728.07263128424;7531.887021694679;7769.223077692228;7770.9611451942665;7787.883181752264;7790.766277011685;1767.7613522174536;1796.6047469556743;2165.9891700541516;2204.8456608037445;2213.9335819925427;2232.687423760675;2241.013953860296;2241.293992392399;2242.5739109569213;2243.181238847817;2154.7522034965946;2182.4652960024814;2184.421128400975;2185.792349726781;2186.857854239477;2176.0534167637047;2184.9184653596494;2207.7461091974387;2211.7235345581803;2216.612092883739;2207.4591725027312;2240.6078936186104;2259.706238189025;2262.049939025613;2286.942826429336;7618.466707330434;7720.536767793894;7730.7680769577;7731;7747.147813740484;4553.658475614309;4594.563516465931;4596.563326484012;4597.770111494412;4611.815527378901;2989.282572182682;3004.2339203503184;3011.051518771602;3017.5021121514924;3157.3369592385397;954.079313462512;956.3114557518576;982.5922242269521;1016.3850870223556;1022.3405903192399;10847.67456976292;10849.728756781144;10877.292975956616;10891.83662245067;10902.291351062155;4075.2053349597136;4092.4066010428323;4173.08192197716;4179.707420480538;4179.937300940488;9214.815703685888;9226.30802689801;9228.031056738946;9242.445453272825;9247.445153290824;4597.540245975376;4626.144163329727;4631.814727410933;4635.466921304026;4640.512746161605;4194.580541945842;4206;4217.156568686275;4231.301835197158;4240.427542281815;3211.4861622140347;3260.0382887048377;3505.8422370993367;3527.3827080260853;3551.8224088795973;1098.8956049175315;1110.1063643766684;1118.9216754827246;1121.2991880074953;1126.0934947367284;960.7790208252142;1165.5745652836654;1225.2893321873291;1231.9815202771965;1268.2010333489843;7125.42996560274;7126.928730712627;7149.213586505481;7175.067241258603;7187.5687458752645;1073.355986408149;1078.7087486378791;1084.0947808579767;1092.2736380307165;1102.0412241349916;5665.66006039639;5791.131330300404;5803.622764520293;5812.941870581325;5814.622049566765;8881.689140880038;9416.869975602971;9431.575579098957;9439.76400589977;9450.291228157916;9365.250779937589;9388.2020028297;9389.295802814817;9423.48170850596;9427.198688111588;7145.606991615511;7168.0681511402145;7169.426445884421;7192.92807071933;7196.64016799158;7073.964630176816;7127.251638578082;7150.034745309316;7162.7493037743425;7165.498415110892;7905.56519391416;7975.481593696391;8030.87953680707;8190.713325033834;8217.630206640595;8225.424220304521;8279.172082791914;8287;8302.086770455366;8358.247757701836;6466.224053113657;6636.13730215069;6644.33556644351;6659.966700166468;6692.498062645418;6690.19717633887;6809.727610895508;7092;7290.890636640561;7292.270772922878;6927.480438967198;6984.092068031327;6986.406155476841;7103.289671032855;7135.714571417086;6891.414229790524;6894.517383783087;6915.239323674291;6930.861382772216;6956.513044086865;6945.965270173617;6996.755113571001;6997;6998.685059172248;7009.404200643003;6250.874982500415;6384.744610215539;6391.616503009835;6393.904091438539;6399.04014397844;6346.174997250328;6484.4812415005745;6486.805395838132;6494.6103233806125;6497.967510162419 22:03:58 INFO - PROCESS | 6440 | |4;prototype.html;332.3253794796563;335.5202061052695;337.22270167264475;338.07367812194593;344.83275611328503;5721;5827.213326200962;5862.149988251706;5877.70611469427;5918.556108291879;5597.580181486384;5788.073908174693;5791.82624521264;5808.59339846211;5829.5919285650025;4507.932381014271;4510.526394728556;4515.864524064284;4523.683342166049;4526.936170000047;4299.968007678166;4383.167198232326;4385.144896745136;4478.373027776114;4517.503074661785;4422.889427764299;4428.1807865544815;4429.77851107445;4440.4893437254805;4443.111377724452;4415.602595766396;4417.204903117422;4420.651701231123;4421.182081314951;4464.107178564268;3919.3729003359613;3922.6469617734256;3931.0761970936805;3942.940855887164;3963.7819919904396;5425.945740542584;5560.944390556083;5592.496675299242;5600.383957764642;5608.467195616451;5167.534921857051;5175.482451754794;5178.922316165261;5188.143956247215;5192.402873669538;5910.704464776744;6210.627362358227;6218.657973811484;6220.377962203744;6226.097215903714;4186.434831297796;4230.259704551691;4254.553271906467;4266.701330906838;4297.720648157891;6358.427741503287;6445.677716114175;6453.419192272718;6453.967730161319;6455.2253729552285;4403.295472724413;4414.668899832525;4415.2935530315;4416.602505774432;4417.536158703354;4316.1583491219535;4438.090191510732;4448.666350023761;4451.421315229064;4452.755098469615;5156.278121063054;5163.535281824654;5171.3277273954145;5176.1459359206065;5190.584753219809;6132.816015519452;6138.447539721446;6185.690715464209;6194.628322300677;6202.1937148171355;1532.5632194824423;1557.7196104701263;1562.7733978573044;1572.2924683892295;1573.2841557091497;1052.1057101463696;1052.6263176572359;1058.9947050264852;1059.3590877519143;1078.9730256743644;5892.234009578728;5902.409759024149;5904.498117659961;5910.34986151523;5915.024021036566;6381.1066450696935;6382.744690212339;6383.9361606383345;6385.425311721874;6387.073874288295;5883.764649413975;5884.2350494436305;5889.911651325147;5890.617109887844;5890.969080410911;3372.629010808809;3457.2048428861;3457.6715212054814;3467.5318831957866;3472.2187507811045;6649.335066493312;6666.466682665375;6669.333066693292;6776;6776.69504872282;6657.1012913257055;6664.633445160466;6668.966310221924;6672.86654266912;6673.265940746514;6556.836079097965;6716.059751634775;6725.529212955046;6742.0224067509935;6742.359475849787;6176.28972668131;6325.430711236011;6444.9033264502;6445.6454894981225;6446.774362897276;6184.81445556634;6291.370862913672;6352.205974253218;6352.650604216812;6375.840603984938;6690.632015239208;6697.229818570927;6703.765368212089;6704.396604305636;6707.798766037026;4251.511076226274;4391.912161756683;4443;4444.155610434136;4445.444319460067;6369.649669268049;6384.6169229846355;6390.616563006234;6393.1369265148605;6394.7442102317245;6208.720607572761;6214.875702486015;6214.937850621436;6217.222847144107;6221.657808820558;6081.817545473643;6155.169052177898;6198.349173336735;6198.81403557894;6206.379362063757;6119.8470038247615;6161.784337548164;6175.722092505758;6187.226596675416;6191.907121393273;6308.022256550242;6308.779192728416;6309.589876658002;6312.4634406076;6317.842053948688;6007.429294217042;6080.69596520181;6156.815295541141;6168.475679567287;6172.598781079216 22:03:58 INFO - PROCESS | 6440 | |5;yui.html;738.8596166728321;813.9511629302247;828.6064119543214;851.5912362066208;866.8266346730653;13505;13711.38298776555;13728.862711372883;13742.312884355792;13757.449702011907;12669.176503527264;12766.617001489947;12770.80843787344;14493.49272775453;14766.63083422915;830.646975035609;833.9700469919644;834.0783434305097;836.5984327522792;838.0237023867187;824.5794644731201;825.6243409248777;825.6614787936948;825.9628316725732;826.9710560130396;856.9957150214271;857.5755001274347;857.9184977427135;862.4006315610653;864.9567521623925;855.9315254779602;858.1289990659485;864.7362554420899;865.523961820996;865.5412631305418;639.9488040956759;641.6470940982441;641.8170821315903;641.8170821315949;642.1267076775628;13646.931765341109;13800.93099534506;13801.51694690681;13804.378802953892;13814;12171.756564868652;12341.382930853511;12458.937705311506;12476.75046499074;12623.747525049539;10212.744681383025;10376.16990640747;10473.47632618366;10676.946615266874;11031.448427578589;3591.8024508652015;3668.834902429397;3685.7972811495624;3686.4654625079484;3689.6494832990825;41374.58625413737;45017.324740128926;48728;54182.18726719063;54198.45801541973;10177.898221017844;10281.897181028095;10311.432871192006;10744.73138171552;10806.135509159405;18109.36617218391;18110.366137185134;18140.365087222148;18148.36480723195;18155.636887262444;7351.742689005859;7482.326590606872;7530.058742657168;7729.265719756614;7807;4314.115606300701;4375.343698445195;4391.187630288407;4400.69195156336;4434.5343738907595;3112.5486804413686;3143.8899638512544;3145.2294187924103;3147.779655424144;3148.98425507871;514.8918727067282;538.8572028412474;542.6879544262065;555.3030946162621;559.7732918168149;571.711285800668;607.6202373516552;693.3111790246194;711.3206887422602;719.0472623773521;8596.8710469343;8618.870716939251;8724.127587241352;8810.19067648785;9033.412828166149;662.7481557008402;703.736098962889;706.7773651299824;706.9823254418578;716.3194964783477;3988.4814974053766;4000.4199391088127;4296.914061718748;4477.9328310074725;4508.864734057984;49539;50999.235011474855;51827.74086129545;52103.73948130235;52224.738876305375;49190.50809491931;49240.261396079084;49494.50505494899;49549.25676114861;49691.25463118056;41601.37597936033;44699.10601788011;50990;50999.235011474855;51417;8592.3555733319;8789.560521974003;8945.883356392258;8973.147550982647;9264.53677316145;30919.69080309258;33796.3240735179;34696.306073878884;34935.47596786101;35333.823330883184;35479.645203548665;40529.594704053765;41863.58136418597;42221;42233.57766422296;11108.61119860801;11133.832992504957;11135.721606959567;11142.331460112418;11402.973732364127;12411.503539858666;12548.937255313665;12632.873671263538;13213.933930330288;13225.537106201236;11242.437878106226;11266.324020558794;11553.133514986577;11650.70873228176;11709.355985420883;11666.394064205166;11892.464839082435;11955.103367247666;12364;12391.566295179626;11394.772104558027;11689.415529223335;11825.82261266099;11863.703407414883;12088.879111209128;9471.479068651015;9657.372270802376;9664.323497355117;9677.242588635207;9771.361732332089;8968.417052891542;9088.863667044867;9959.153471954965;9987.550560224952;10027.598896044368 22:03:58 INFO - PROCESS | 6440 | __end_tp_report 22:03:58 INFO - PROCESS | 6440 | __start_cc_report 22:03:58 INFO - PROCESS | 6440 | _x_x_mozilla_cycle_collect,802 22:03:58 INFO - PROCESS | 6440 | __end_cc_report 22:03:58 INFO - PROCESS | 6440 | __startTimestamp1452060238340__endTimestamp 22:03:58 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | ------- Summary: start ------- 22:03:58 INFO - PROCESS | 6440 | Number of tests: 6 22:03:58 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | [#0] dojo.html Cycles:165 Average:5090.91 Median:5317.26 stddev:646.97 (12.2%) stddev-sans-first:629.38 22:03:58 INFO - PROCESS | 6440 | Values: 3077.4 3188.2 3241.1 3241.3 3273.8 4966.2 5093.6 5166.5 5201.6 5242.7 4991.5 5028.4 5030.7 5037.2 5060.8 4680.5 4703.7 4726.8 4800.0 4840.2 532.5 4545.8 4716.9 4738.3 4793.4 4652.2 4672.8 4723.3 4748.8 4758.7 4684.4 4688.9 4694.9 4700.9 4731.6 4584.9 4663.3 4688.2 4694.5 4747.3 4853.6 4896.9 4910.6 4929.0 4963.5 5472.2 5504.7 5506.9 5551.3 5552.0 5350.0 5490.3 5490.8 5540.9 5543.0 5166.9 5181.5 5184.0 5203.3 5210.5 5552.7 5595.7 5607.8 5609.4 5610.5 5317.3 5416.9 5419.9 5424.2 5426.8 5451.5 5468.2 5540.8 5540.8 5577.3 5491.1 5495.3 5496.8 5496.9 5500.3 5394.9 5497.5 5498.5 5504.7 5553.2 4273.5 4339.3 4340.0 4377.9 4378.0 3827.8 3830.0 3839.8 3855.6 3867.0 4965.3 5056.5 5071.8 5085.9 5102.1 5518.2 5545.8 5546.6 5551.2 5573.8 5134.4 5138.1 5141.7 5142.5 5184.8 4674.6 4757.9 4762.1 4767.6 4856.0 5692.4 5699.0 5711.9 5714.6 5718.2 5470.5 5473.7 5483.3 5487.0 5487.5 5569.6 5575.9 5620.4 5660.3 5683.7 5432.4 5472.3 5480.6 5482.9 5486.1 5515.0 5526.2 5528.4 5529.0 5583.7 5271.0 5378.4 5383.8 5445.6 5499.0 5420.1 5463.7 5464.0 5466.1 5471.2 5221.0 5361.8 5398.1 5444.1 5448.6 5296.0 5359.5 5400.2 5417.8 5433.0 5195.0 5202.1 5204.6 5205.7 5214.7 22:03:58 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | [#1] ext.html Cycles:180 Average:44328.79 Median:319638.50 stddev:71459.31 (22.4%) stddev-sans-first:71584.04 22:03:58 INFO - PROCESS | 6440 | Values: 511.2 535.8 540.4 561.7 584.6 28065.4 38088.0 38897.6 38914.6 39048.0 42039.4 42836.0 42864.8 42926.4 42992.0 7280.4 7396.3 8528.5 8731.0 9800.4 9898.4 10022.2 10025.6 10034.4 10038.4 10033.7 10184.9 10191.5 10196.6 10211.3 7527.7 7604.8 10082.9 10316.1 10321.7 9901.0 10040.7 10065.0 10069.7 10069.7 37366.6 37798.6 37810.0 37833.4 37920.0 3001.5 3058.5 3078.9 3299.8 3634.0 29550.0 29666.3 29690.9 29920.3 30342.0 3633.0 3755.3 3755.5 3861.0 4005.9 262620.0 263515.0 265462.0 265999.0 267237.9 54018.5 55647.4 60232.4 60683.0 60749.4 65293.0 66086.7 66338.0 66473.7 66488.7 13489.4 13610.3 13617.2 13622.3 13624.3 11060.3 11126.4 11127.8 11138.8 11142.0 19271.7 20357.4 20390.2 20403.8 20568.8 7266.9 7421.4 7431.7 7478.7 7512.4 906.0 924.0 962.3 979.5 1013.9 28596.6 28870.3 28884.4 29010.7 29263.6 990.3 1025.7 1083.6 1087.5 1103.4 14645.3 14915.7 14975.3 15176.2 15264.7 312894.0 317997.0 319395.0 319882.0 320475.0 52191.5 53039.5 53055.5 53136.0 53137.0 1290.6 1303.2 1319.5 1320.2 1337.6 25817.5 27374.7 28627.3 29524.6 29860.0 47167.0 47847.3 47849.5 47851.0 47853.3 227546.0 229601.0 231289.0 231889.0 232262.0 27521.4 27805.7 29072.7 31204.0 31346.0 37891.1 38223.8 38906.8 39464.6 42099.4 34453.0 34560.7 34853.7 35203.8 35804.5 29903.7 34554.7 35361.5 36063.3 36215.3 32242.4 32386.0 32649.5 32731.7 33389.8 30118.4 31614.2 32019.8 32115.7 32391.7 23571.5 24534.3 24616.6 25109.5 25902.7 22:03:58 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | [#2] jquery.html Cycles:180 Average:27868.92 Median:353.83 stddev:84497.42 (23880.9%) stddev-sans-first:84709.19 22:03:58 INFO - PROCESS | 6440 | Values: 348.6 351.4 356.3 356.8 358.6 14008.8 14454.4 16047.5 16217.3 16839.8 15576.5 15816.6 16467.3 16497.8 16512.3 5007.8 5345.9 5350.8 5351.4 5351.9 4056.8 4116.7 5237.9 5643.6 5766.7 5694.1 5699.2 5724.2 5747.1 5747.3 5582.1 5805.9 5806.6 5806.9 5810.2 5090.5 5102.2 5113.5 5154.1 5155.6 20483.0 20641.0 20724.4 20759.3 20869.3 2129.4 2130.3 2167.6 2213.3 2253.9 12916.7 13011.5 13131.7 13352.5 13445.1 1782.4 1782.8 1786.3 1802.8 1844.8 57510.7 59260.0 59425.1 59601.4 59778.4 9581.0 9863.8 9865.2 9885.4 9939.4 12806.1 12916.9 12926.9 12946.4 12947.5 7390.0 7399.5 7442.7 7444.5 7542.0 7136.2 7155.0 7184.0 7205.3 7214.6 5850.6 5892.3 5900.7 5921.1 5961.3 1672.5 1689.5 1715.5 1743.8 1763.5 895.5 922.5 987.4 1073.8 1077.5 14950.2 15506.8 16418.0 16592.9 16755.7 933.6 940.8 945.4 985.9 986.1 9662.2 9849.0 9963.4 10047.6 10096.5 506893.0 508186.0 515321.0 527215.0 533111.0 67845.3 68627.7 69255.3 69887.7 70479.3 1128.3 1130.1 1137.1 1147.4 1150.9 15143.8 15176.5 15441.8 15625.7 15850.6 34145.0 34262.0 34429.5 34475.0 34496.8 43972.0 44240.6 44535.3 44961.6 46153.0 9662.9 9739.9 9781.3 9797.2 9799.9 12980.6 16519.8 17432.8 17537.0 17766.8 11879.8 13219.9 13939.6 14056.8 14179.9 14314.3 14347.0 14512.1 14763.4 15194.7 14824.4 15177.2 15287.2 15463.8 15792.7 9342.5 9444.4 9480.4 9760.4 9795.0 10229.2 10256.4 12465.8 14215.9 14244.6 22:03:58 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | [#3] mootools.html Cycles:180 Average:5377.65 Median:6396.47 stddev:2823.63 (44.1%) stddev-sans-first:2815.07 22:03:58 INFO - PROCESS | 6440 | Values: 1319.0 1434.5 1436.1 1565.5 1756.8 6873.8 7624.0 7718.7 7726.2 7728.1 7531.9 7769.2 7771.0 7787.9 7790.8 1767.8 1796.6 2166.0 2204.8 2213.9 2232.7 2241.0 2241.3 2242.6 2243.2 2154.8 2182.5 2184.4 2185.8 2186.9 2176.1 2184.9 2207.7 2211.7 2216.6 2207.5 2240.6 2259.7 2262.0 2286.9 7618.5 7720.5 7730.8 7731.0 7747.1 4553.7 4594.6 4596.6 4597.8 4611.8 2989.3 3004.2 3011.1 3017.5 3157.3 954.1 956.3 982.6 1016.4 1022.3 10847.7 10849.7 10877.3 10891.8 10902.3 4075.2 4092.4 4173.1 4179.7 4179.9 9214.8 9226.3 9228.0 9242.4 9247.4 4597.5 4626.1 4631.8 4635.5 4640.5 4194.6 4206.0 4217.2 4231.3 4240.4 3211.5 3260.0 3505.8 3527.4 3551.8 1098.9 1110.1 1118.9 1121.3 1126.1 960.8 1165.6 1225.3 1232.0 1268.2 7125.4 7126.9 7149.2 7175.1 7187.6 1073.4 1078.7 1084.1 1092.3 1102.0 5665.7 5791.1 5803.6 5812.9 5814.6 8881.7 9416.9 9431.6 9439.8 9450.3 9365.3 9388.2 9389.3 9423.5 9427.2 7145.6 7168.1 7169.4 7192.9 7196.6 7074.0 7127.3 7150.0 7162.7 7165.5 7905.6 7975.5 8030.9 8190.7 8217.6 8225.4 8279.2 8287.0 8302.1 8358.2 6466.2 6636.1 6644.3 6660.0 6692.5 6690.2 6809.7 7092.0 7290.9 7292.3 6927.5 6984.1 6986.4 7103.3 7135.7 6891.4 6894.5 6915.2 6930.9 6956.5 6946.0 6996.8 6997.0 6998.7 7009.4 6250.9 6384.7 6391.6 6393.9 6399.0 6346.2 6484.5 6486.8 6494.6 6498.0 22:03:58 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | [#4] prototype.html Cycles:180 Average:5191.02 Median:5887.07 stddev:1570.98 (26.7%) stddev-sans-first:1532.48 22:03:58 INFO - PROCESS | 6440 | Values: 332.3 335.5 337.2 338.1 344.8 5721.0 5827.2 5862.1 5877.7 5918.6 5597.6 5788.1 5791.8 5808.6 5829.6 4507.9 4510.5 4515.9 4523.7 4526.9 4300.0 4383.2 4385.1 4478.4 4517.5 4422.9 4428.2 4429.8 4440.5 4443.1 4415.6 4417.2 4420.7 4421.2 4464.1 3919.4 3922.6 3931.1 3942.9 3963.8 5425.9 5560.9 5592.5 5600.4 5608.5 5167.5 5175.5 5178.9 5188.1 5192.4 5910.7 6210.6 6218.7 6220.4 6226.1 4186.4 4230.3 4254.6 4266.7 4297.7 6358.4 6445.7 6453.4 6454.0 6455.2 4403.3 4414.7 4415.3 4416.6 4417.5 4316.2 4438.1 4448.7 4451.4 4452.8 5156.3 5163.5 5171.3 5176.1 5190.6 6132.8 6138.4 6185.7 6194.6 6202.2 1532.6 1557.7 1562.8 1572.3 1573.3 1052.1 1052.6 1059.0 1059.4 1079.0 5892.2 5902.4 5904.5 5910.3 5915.0 6381.1 6382.7 6383.9 6385.4 6387.1 5883.8 5884.2 5889.9 5890.6 5891.0 3372.6 3457.2 3457.7 3467.5 3472.2 6649.3 6666.5 6669.3 6776.0 6776.7 6657.1 6664.6 6669.0 6672.9 6673.3 6556.8 6716.1 6725.5 6742.0 6742.4 6176.3 6325.4 6444.9 6445.6 6446.8 6184.8 6291.4 6352.2 6352.7 6375.8 6690.6 6697.2 6703.8 6704.4 6707.8 4251.5 4391.9 4443.0 4444.2 4445.4 6369.6 6384.6 6390.6 6393.1 6394.7 6208.7 6214.9 6214.9 6217.2 6221.7 6081.8 6155.2 6198.3 6198.8 6206.4 6119.8 6161.8 6175.7 6187.2 6191.9 6308.0 6308.8 6309.6 6312.5 6317.8 6007.4 6080.7 6156.8 6168.5 6172.6 22:03:58 INFO - PROCESS | 6440 | 22:03:58 INFO - PROCESS | 6440 | [#5] yui.html Cycles:180 Average:13620.32 Median:24566.82 stddev:15268.85 (62.2%) stddev-sans-first:15281.04 22:03:58 INFO - PROCESS | 6440 | Values: 738.9 814.0 828.6 851.6 866.8 13505.0 13711.4 13728.9 13742.3 13757.4 12669.2 12766.6 12770.8 14493.5 14766.6 830.6 834.0 834.1 836.6 838.0 824.6 825.6 825.7 826.0 827.0 857.0 857.6 857.9 862.4 865.0 855.9 858.1 864.7 865.5 865.5 639.9 641.6 641.8 641.8 642.1 13646.9 13800.9 13801.5 13804.4 13814.0 12171.8 12341.4 12458.9 12476.8 12623.7 10212.7 10376.2 10473.5 10676.9 11031.4 3591.8 3668.8 3685.8 3686.5 3689.6 41374.6 45017.3 48728.0 54182.2 54198.5 10177.9 10281.9 10311.4 10744.7 10806.1 18109.4 18110.4 18140.4 18148.4 18155.6 7351.7 7482.3 7530.1 7729.3 7807.0 4314.1 4375.3 4391.2 4400.7 4434.5 3112.5 3143.9 3145.2 3147.8 3149.0 514.9 538.9 542.7 555.3 559.8 571.7 607.6 693.3 711.3 719.0 8596.9 8618.9 8724.1 8810.2 9033.4 662.7 703.7 706.8 707.0 716.3 3988.5 4000.4 4296.9 4477.9 4508.9 49539.0 50999.2 51827.7 52103.7 52224.7 49190.5 49240.3 49494.5 49549.3 49691.3 41601.4 44699.1 50990.0 50999.2 51417.0 8592.4 8789.6 8945.9 8973.1 9264.5 30919.7 33796.3 34696.3 34935.5 35333.8 35479.6 40529.6 41863.6 42221.0 42233.6 11108.6 11133.8 11135.7 11142.3 11403.0 12411.5 12548.9 12632.9 13213.9 13225.5 11242.4 11266.3 11553.1 11650.7 11709.4 11666.4 11892.5 11955.1 12364.0 12391.6 11394.8 11689.4 11825.8 11863.7 12088.9 9471.5 9657.4 9664.3 9677.2 9771.4 8968.4 9088.9 9959.2 9987.6 10027.6 22:03:58 INFO - PROCESS | 6440 | -------- Summary: end -------- 22:03:58 INFO - PROCESS | 6440 | 22:03:59 INFO - TEST-INFO | 6440: exit 0 22:03:59 INFO - TEST-OK | dromaeo_css | took 1114043ms 22:03:59 INFO - TEST-START | kraken 22:03:59 INFO - operating with platform_type : linux_ 22:03:59 INFO - Initialising browser for kraken test... 22:03:59 INFO - TEST-INFO | started process 6639 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7mPEsL/profile http://localhost:44546/getInfo.html) 22:04:03 INFO - PROCESS | 6639 | __metrics Screen width/height:1600/1200 22:04:03 INFO - PROCESS | 6639 | colorDepth:24 22:04:03 INFO - PROCESS | 6639 | Browser inner width/height: 1024/697 22:04:03 INFO - PROCESS | 6639 | __metrics 22:04:03 INFO - PROCESS | 6639 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:04:03 INFO - PROCESS | 6639 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:04:05 INFO - TEST-INFO | 6639: exit 0 22:04:05 INFO - Browser initialized. 22:04:05 INFO - Running cycle 1/1 for kraken test... 22:04:05 INFO - TEST-INFO | started process 6720 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp7mPEsL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:04:16 INFO - PROCESS | 6720 | RSS: Main: 149643264 22:04:16 INFO - PROCESS | 6720 | 22:04:19 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44546/tests/kraken/driver.html?testName=audio-beat-detection) 22:04:19 INFO - PROCESS | 6720 | RSS: Main: 183713792 22:04:19 INFO - PROCESS | 6720 | 22:04:22 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44546/tests/kraken/driver.html?testName=audio-dft) 22:04:23 INFO - PROCESS | 6720 | RSS: Main: 173178880 22:04:23 INFO - PROCESS | 6720 | 22:04:25 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44546/tests/kraken/driver.html?testName=audio-fft) 22:04:26 INFO - PROCESS | 6720 | RSS: Main: 160686080 22:04:26 INFO - PROCESS | 6720 | 22:04:28 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44546/tests/kraken/driver.html?testName=audio-oscillator) 22:04:28 INFO - PROCESS | 6720 | RSS: Main: 161873920 22:04:28 INFO - PROCESS | 6720 | 22:04:31 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44546/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:04:31 INFO - PROCESS | 6720 | RSS: Main: 164376576 22:04:31 INFO - PROCESS | 6720 | 22:04:34 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44546/tests/kraken/driver.html?testName=imaging-darkroom) 22:04:35 INFO - PROCESS | 6720 | RSS: Main: 165650432 22:04:35 INFO - PROCESS | 6720 | 22:04:38 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44546/tests/kraken/driver.html?testName=imaging-desaturate) 22:04:39 INFO - PROCESS | 6720 | RSS: Main: 165978112 22:04:39 INFO - PROCESS | 6720 | 22:04:41 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44546/tests/kraken/driver.html?testName=json-parse-financial) 22:04:42 INFO - PROCESS | 6720 | RSS: Main: 165945344 22:04:42 INFO - PROCESS | 6720 | 22:04:43 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44546/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:04:44 INFO - PROCESS | 6720 | RSS: Main: 177094656 22:04:44 INFO - PROCESS | 6720 | 22:04:46 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44546/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:04:46 INFO - PROCESS | 6720 | RSS: Main: 176185344 22:04:46 INFO - PROCESS | 6720 | 22:04:49 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44546/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:04:49 INFO - PROCESS | 6720 | RSS: Main: 180584448 22:04:49 INFO - PROCESS | 6720 | 22:04:52 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:04:52 INFO - PROCESS | 6720 | RSS: Main: 190795776 22:04:52 INFO - PROCESS | 6720 | 22:04:55 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:04:55 INFO - PROCESS | 6720 | RSS: Main: 189120512 22:04:55 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | Cycle 1(1): loaded http://localhost:44546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44546/tests/kraken/driver.html?testName=ai-astar) 22:04:57 INFO - PROCESS | 6720 | RSS: Main: 247218176 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | __start_tp_report 22:04:57 INFO - PROCESS | 6720 | _x_x_mozilla_page_load 22:04:57 INFO - PROCESS | 6720 | _x_x_mozilla_page_load_details 22:04:57 INFO - PROCESS | 6720 | |i|pagename|runs| 22:04:57 INFO - PROCESS | 6720 | |0;ai-astar;110;112;105;111;111;110;101;111;111;102 22:04:57 INFO - PROCESS | 6720 | |1;audio-beat-detection;157;132;151;164;124;148;151;125;167;131 22:04:57 INFO - PROCESS | 6720 | |2;audio-dft;162;163;161;162;161;160;160;161;160;163 22:04:57 INFO - PROCESS | 6720 | |3;audio-fft;72;72;88;94;70;71;73;97;92;71 22:04:57 INFO - PROCESS | 6720 | |4;audio-oscillator;96;96;141;95;97;96;141;95;97;140 22:04:57 INFO - PROCESS | 6720 | |5;imaging-gaussian-blur;109;108;109;109;109;109;110;109;109;109 22:04:57 INFO - PROCESS | 6720 | |6;imaging-darkroom;145;145;145;146;145;146;145;145;146;145 22:04:57 INFO - PROCESS | 6720 | |7;imaging-desaturate;96;96;95;95;97;96;96;95;96;96 22:04:57 INFO - PROCESS | 6720 | |8;json-parse-financial;59;60;58;58;59;65;59;71;57;58 22:04:57 INFO - PROCESS | 6720 | |9;json-stringify-tinderbox;68;67;67;67;67;67;68;73;67;67 22:04:57 INFO - PROCESS | 6720 | |10;stanford-crypto-aes;85;86;86;87;87;88;88;94;88;89 22:04:57 INFO - PROCESS | 6720 | |11;stanford-crypto-ccm;122;117;120;118;119;119;130;122;122;117 22:04:57 INFO - PROCESS | 6720 | |12;stanford-crypto-pbkdf2;146;146;158;157;158;146;164;156;155;158 22:04:57 INFO - PROCESS | 6720 | |13;stanford-crypto-sha256-iterative;69;70;72;69;68;68;69;70;70;70 22:04:57 INFO - PROCESS | 6720 | __end_tp_report 22:04:57 INFO - PROCESS | 6720 | __start_cc_report 22:04:57 INFO - PROCESS | 6720 | _x_x_mozilla_cycle_collect,940 22:04:57 INFO - PROCESS | 6720 | __end_cc_report 22:04:57 INFO - PROCESS | 6720 | __startTimestamp1452060297787__endTimestamp 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | ------- Summary: start ------- 22:04:57 INFO - PROCESS | 6720 | Number of tests: 14 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#0] ai-astar Cycles:10 Average:108.40 Median:111.00 stddev:4.12 (3.7%) stddev-sans-first:4.32 22:04:57 INFO - PROCESS | 6720 | Values: 110.0 112.0 105.0 111.0 111.0 110.0 101.0 111.0 111.0 102.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:151.00 stddev:15.90 (10.5%) stddev-sans-first:16.26 22:04:57 INFO - PROCESS | 6720 | Values: 157.0 132.0 151.0 164.0 124.0 148.0 151.0 125.0 167.0 131.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.50 stddev:1.16 (0.7%) stddev-sans-first:1.20 22:04:57 INFO - PROCESS | 6720 | Values: 162.0 163.0 161.0 162.0 161.0 160.0 160.0 161.0 160.0 163.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#3] audio-fft Cycles:10 Average:80.00 Median:80.50 stddev:11.22 (13.9%) stddev-sans-first:11.52 22:04:57 INFO - PROCESS | 6720 | Values: 72.0 72.0 88.0 94.0 70.0 71.0 73.0 97.0 92.0 71.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:96.00 stddev:21.59 (22.5%) stddev-sans-first:22.35 22:04:57 INFO - PROCESS | 6720 | Values: 96.0 96.0 141.0 95.0 97.0 96.0 141.0 95.0 97.0 140.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 22:04:57 INFO - PROCESS | 6720 | Values: 109.0 108.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 22:04:57 INFO - PROCESS | 6720 | Values: 145.0 145.0 145.0 146.0 145.0 146.0 145.0 145.0 146.0 145.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 22:04:57 INFO - PROCESS | 6720 | Values: 96.0 96.0 95.0 95.0 97.0 96.0 96.0 95.0 96.0 96.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:59.00 stddev:4.33 (7.3%) stddev-sans-first:4.56 22:04:57 INFO - PROCESS | 6720 | Values: 59.0 60.0 58.0 58.0 59.0 65.0 59.0 71.0 57.0 58.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:67.00 stddev:1.87 (2.8%) stddev-sans-first:1.99 22:04:57 INFO - PROCESS | 6720 | Values: 68.0 67.0 67.0 67.0 67.0 67.0 68.0 73.0 67.0 67.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:88.00 stddev:2.49 (2.8%) stddev-sans-first:2.42 22:04:57 INFO - PROCESS | 6720 | Values: 85.0 86.0 86.0 87.0 87.0 88.0 88.0 94.0 88.0 89.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:121.00 stddev:3.84 (3.2%) stddev-sans-first:4.03 22:04:57 INFO - PROCESS | 6720 | Values: 122.0 117.0 120.0 118.0 119.0 119.0 130.0 122.0 122.0 117.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.40 Median:157.50 stddev:6.26 (4.0%) stddev-sans-first:5.85 22:04:57 INFO - PROCESS | 6720 | Values: 146.0 146.0 158.0 157.0 158.0 146.0 164.0 156.0 155.0 158.0 22:04:57 INFO - PROCESS | 6720 | 22:04:57 INFO - PROCESS | 6720 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.18 (1.7%) stddev-sans-first:1.24 22:04:57 INFO - PROCESS | 6720 | Values: 69.0 70.0 72.0 69.0 68.0 68.0 69.0 70.0 70.0 70.0 22:04:57 INFO - PROCESS | 6720 | -------- Summary: end -------- 22:04:57 INFO - PROCESS | 6720 | 22:04:59 INFO - TEST-INFO | 6720: exit 0 22:04:59 INFO - TEST-OK | kraken | took 59939ms 22:04:59 INFO - TEST-START | v8_7 22:04:59 INFO - operating with platform_type : linux_ 22:04:59 INFO - Initialising browser for v8_7 test... 22:04:59 INFO - TEST-INFO | started process 6973 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8pMLyE/profile http://localhost:44546/getInfo.html) 22:05:03 INFO - PROCESS | 6973 | __metrics Screen width/height:1600/1200 22:05:03 INFO - PROCESS | 6973 | colorDepth:24 22:05:03 INFO - PROCESS | 6973 | Browser inner width/height: 1024/697 22:05:03 INFO - PROCESS | 6973 | __metrics 22:05:03 INFO - PROCESS | 6973 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:05:03 INFO - PROCESS | 6973 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:05:04 INFO - TEST-INFO | 6973: exit 0 22:05:04 INFO - Browser initialized. 22:05:04 INFO - Running cycle 1/1 for v8_7 test... 22:05:04 INFO - TEST-INFO | started process 7057 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8pMLyE/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:05:16 INFO - PROCESS | 7057 | RSS: Main: 150564864 22:05:16 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | Cycle 1(1): loaded http://localhost:44546/tests/v8_7/run.html (next: http://localhost:44546/tests/v8_7/run.html) 22:05:38 INFO - PROCESS | 7057 | RSS: Main: 618557440 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | __start_tp_report 22:05:38 INFO - PROCESS | 7057 | _x_x_mozilla_page_load 22:05:38 INFO - PROCESS | 7057 | _x_x_mozilla_page_load_details 22:05:38 INFO - PROCESS | 7057 | |i|pagename|runs| 22:05:38 INFO - PROCESS | 7057 | |0;Richards;167.7008217340265 22:05:38 INFO - PROCESS | 7057 | |1;DeltaBlue;191.5341888527102 22:05:38 INFO - PROCESS | 7057 | |2;Encrypt;279.87685418415896 22:05:38 INFO - PROCESS | 7057 | |3;Decrypt;5251.308900523561 22:05:38 INFO - PROCESS | 7057 | |4;RayTrace;2024.2914979757086 22:05:38 INFO - PROCESS | 7057 | |5;Earley;804.0160642570281 22:05:38 INFO - PROCESS | 7057 | |6;Boyer;12180.722891566265 22:05:38 INFO - PROCESS | 7057 | |7;RegExp;26051.28205128205 22:05:38 INFO - PROCESS | 7057 | |8;Splay;633.9455351488284 22:05:38 INFO - PROCESS | 7057 | |9;NavierStokes;4830.917874396136 22:05:38 INFO - PROCESS | 7057 | __end_tp_report 22:05:38 INFO - PROCESS | 7057 | __startTimestamp1452060338389__endTimestamp 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | ------- Summary: start ------- 22:05:38 INFO - PROCESS | 7057 | Number of tests: 10 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#0] Richards Cycles:1 Average:167.70 Median:167.70 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 167.7 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#1] DeltaBlue Cycles:1 Average:191.53 Median:191.53 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 191.5 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#2] Encrypt Cycles:1 Average:279.88 Median:279.88 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 279.9 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#3] Decrypt Cycles:1 Average:5251.31 Median:5251.31 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 5251.3 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#4] RayTrace Cycles:1 Average:2024.29 Median:2024.29 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 2024.3 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#5] Earley Cycles:1 Average:804.02 Median:804.02 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 804.0 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#6] Boyer Cycles:1 Average:12180.72 Median:12180.72 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 12180.7 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#7] RegExp Cycles:1 Average:26051.28 Median:26051.28 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 26051.3 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#8] Splay Cycles:1 Average:633.95 Median:633.95 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 633.9 22:05:38 INFO - PROCESS | 7057 | 22:05:38 INFO - PROCESS | 7057 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 22:05:38 INFO - PROCESS | 7057 | Values: 4830.9 22:05:38 INFO - PROCESS | 7057 | -------- Summary: end -------- 22:05:38 INFO - PROCESS | 7057 | 22:05:39 INFO - TEST-INFO | 7057: exit 0 22:05:39 INFO - TEST-OK | v8_7 | took 40163ms 22:05:39 INFO - SUITE-END | took 1214s 22:05:39 INFO - Completed test suite (00:20:14) 22:05:39 INFO - Working with test: dromaeo_css 22:05:39 INFO - Generating results file: dromaeo_css 22:05:39 INFO - Working with test: kraken 22:05:39 INFO - Generating results file: kraken 22:05:39 INFO - javascript benchmark 22:05:39 INFO - Working with test: v8_7 22:05:39 INFO - Generating results file: v8_7 22:05:39 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:05:39 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 22:05:39 INFO - process_Request line: dromaeo_css 6895.55 graph.html#tests=[[72,1,35]] 22:05:39 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:05:39 INFO - process_Request line: kraken 1514.70 graph.html#tests=[[232,1,35]] 22:05:39 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:05:39 INFO - process_Request line: v8_7 19592.56 graph.html#tests=[[230,1,35]] 22:05:39 INFO - RETURN: dromaeo_css: 6895.55 22:05:39 INFO - RETURN: kraken: 1514.70 22:05:39 INFO - RETURN: v8_7: 19592.56 22:05:39 INFO - javascript benchmark 22:05:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3077.3691393264385, 3188.171075520364, 3241.141097609134, 3241.335526217126, 3273.7872038317514, 4966.180580204271, 5093.617978651598, 5166.457521960197, 5201.557867581256, 5242.659227150232, 4991.500849915006, 5028.371453568304, 5030.723310217936, 5037.1688671369275, 5060.772265248063, 4680.485146633859, 4703.670743047971, 4726.787294571753, 4800.039992001613, 4840.225563909775, 532.5018645980937, 4545.795439205243, 4716.858494245161, 4738.336632871384, 4793.376861008064, 4652.209124448852, 4672.813087476497, 4723.267893476483, 4748.76256187191, 4758.690685105492, 4684.3910291662205, 4688.929666055012, 4694.882627934295, 4700.858974230779, 4731.550502702238, 4584.862454126382, 4663.347131401607, 4688.249880019214, 4694.50707675696, 4747.264174053027, 4853.635977301681, 4896.902061958741, 4910.558049775537, 4929.0, 4963.503649635044, 5472.151816468454, 5504.724763761836, 5506.862328441781, 5551.333839939192, 5552.028395030853, 5349.973250133763, 5490.3411590609385, 5490.835274941758, 5540.9168862467095, 5543.0, 5166.948330516647, 5181.5077567631015, 5184.040952423814, 5203.271541984125, 5210.452902445264, 5552.7223638817895, 5595.720213989284, 5607.8317650469735, 5609.354924183689, 5610.49505544503, 5317.255584218213, 5416.891662166735, 5419.891602167934, 5424.186372044225, 5426.83719488408, 5451.48210919962, 5468.234447177399, 5540.806071787468, 5540.833774986757, 5577.3028371453565, 5491.066518691832, 5495.313085864267, 5496.835094947078, 5496.917546236809, 5500.28496295487, 5394.946050539523, 5497.47773961473, 5498.532624726863, 5504.669719816824, 5553.194786755978, 4273.465816772903, 4339.327404252346, 4340.023494713664, 4377.934330985037, 4378.014946636981, 3827.78947157909, 3830.042489377656, 3839.7696138231236, 3855.575886652466, 3867.0, 4965.280034394992, 5056.469070747518, 5071.771770270347, 5085.923711144335, 5102.08162530748, 5518.19986102013, 5545.833624991176, 5546.611737178358, 5551.167324901297, 5573.832785016456, 5134.409542902539, 5138.126518491866, 5141.665791723526, 5142.51146141116, 5184.766685499161, 4674.602658773974, 4757.857264282077, 4762.142814293391, 4767.570918617341, 4856.0, 5692.37383887772, 5698.971505142447, 5711.914321285184, 5714.571407144397, 5718.170865224519, 5470.53500452466, 5473.684210526316, 5483.287172667688, 5487.0, 5487.506124448819, 5569.610127291051, 5575.916361254665, 5620.3536593290655, 5660.29246344207, 5683.744231509675, 5432.402435732145, 5472.34331880161, 5480.6437581557075, 5482.945170548403, 5486.149646804752, 5515.007298686275, 5526.22632831412, 5528.364238112668, 5528.977139229255, 5583.664980101207, 5270.973645131749, 5378.381486129145, 5383.757730902041, 5445.646033007842, 5499.037668407933, 5420.105682562333, 5463.72681365938, 5463.972680136574, 5466.070767969375, 5471.206675032177, 5221.0, 5361.758720857492, 5398.13629819227, 5444.1017232156255, 5448.645838020516, 5295.973520132375, 5359.464053594609, 5400.2439658447065, 5417.756200971045, 5433.049216387196, 5195.038917800219, 5202.063628546898, 5204.609654275869, 5205.6616319939085, 5214.713190774392], "unit": "score", "name": "dojo.html", "value": 5053.393697927822}, {"lowerIsBetter": false, "replicates": [511.15148852904184, 535.8446050645313, 540.42174872886, 561.6994907724363, 584.5907864494852, 28065.43869122616, 38088.0, 38897.61102388975, 38914.610853891456, 39047.99999999996, 42039.36940945888, 42836.0, 42864.78567607165, 42926.35610465846, 42992.0, 7280.417566594686, 7396.334329910307, 8528.488290702546, 8731.0, 9800.362976406546, 9898.35660682057, 10022.24833137514, 10025.59897604095, 10034.448105354202, 10038.397696138256, 10033.749156271078, 10184.949075254613, 10191.54138063789, 10196.592136314539, 10211.33626314291, 7527.698892044312, 7604.847903041936, 10082.9495852521, 10316.123129533999, 10321.690349289533, 9901.0, 10040.748981275454, 10065.043820837009, 10069.69790906274, 10069.748256293578, 37366.62633373659, 37798.622013779786, 37810.0, 37833.43249851254, 37920.0, 3001.4630396508455, 3058.464768665497, 3078.8614512346776, 3299.8350082496017, 3633.9636603634153, 29550.0, 29666.25834354137, 29690.85154574235, 29920.251993700113, 30342.0, 3632.963670363289, 3755.324041672498, 3755.4930084438524, 3861.0347413146715, 4005.8783540608674, 262620.0, 263515.0, 265462.0, 265999.0, 267237.93144822743, 54018.459815401344, 55647.443525564224, 60232.39767602355, 60683.0, 60749.39250607526, 65293.0, 66086.66956665186, 66338.0, 66473.66763166153, 66488.66755666287, 13489.392977316043, 13610.251436170907, 13617.182969021891, 13622.250776207205, 13624.318784060757, 11060.281081729661, 11126.443677816238, 11127.833082503768, 11138.832917506244, 11142.0, 19271.662284400165, 20357.389278321672, 20390.184392624127, 20403.79596204019, 20568.79431205669, 7266.92733072673, 7421.406287496988, 7431.699452595774, 7478.70085196586, 7512.436567257478, 905.968291109808, 924.0, 962.2975228083538, 979.4906648542718, 1013.8580598716186, 28596.571051434246, 28870.278243044093, 28884.42231155407, 29010.709892901225, 29263.561046584317, 990.2820455169945, 1025.6922923123182, 1083.5719890643152, 1087.521490544174, 1103.4151899493281, 14645.267736613127, 14915.701685966436, 14975.251237438084, 15176.24118794078, 15264.694706105816, 312894.0, 317997.0, 319395.0, 319882.0, 320475.0, 52191.47808521942, 53039.46960530423, 53055.469445305826, 53136.0, 53137.0, 1290.6321698315935, 1303.1985329022773, 1319.4788058716945, 1320.1880843281506, 1337.5519201067557, 25817.483650327264, 27374.72625273722, 28627.28431789222, 29524.557131642614, 29860.0, 47167.0, 47847.28229076497, 47849.521504784505, 47851.0, 47853.28220076771, 227546.0, 229601.0, 231289.0, 231889.0, 232262.0, 27521.449571008867, 27805.748741307096, 29072.709272907847, 31204.0, 31346.0, 37891.05272368213, 38223.80888095542, 38906.80546597249, 39464.60535394609, 42099.368509472995, 34453.0, 34560.65439345675, 34853.65146348504, 35203.82398087993, 35804.462933056544, 29903.700962990963, 34554.65445345615, 35361.469577956865, 36063.27873442569, 36215.27569448649, 32242.35515289728, 32386.0, 32649.510257345682, 32731.672683272864, 33389.83305083459, 30118.3976320468, 31614.209644759067, 32019.839900800347, 32115.67884321127, 32391.676083238865, 23571.528569428174, 24534.263972080866, 24616.6307505384, 25109.497810044064, 25902.740972590032], "unit": "score", "name": "ext.html", "value": 18197.92242574645}, {"lowerIsBetter": false, "replicates": [348.6147806673627, 351.3552630922259, 356.3016487684138, 356.8037579331367, 358.6341931230144, 14008.789868151986, 14454.421823127062, 16047.51857444277, 16217.270222839987, 16839.831601683996, 15576.532704018897, 15816.604584885356, 16467.34130634773, 16497.752533712002, 16512.339506419758, 5007.799688012475, 5345.946540534584, 5350.759215835296, 5351.411344752094, 5351.919721204165, 4056.7565946043183, 4116.711830171889, 5237.869053273661, 5643.6049476536655, 5766.711664416784, 5694.117411801178, 5699.2306038684865, 5724.198612194296, 5747.109198074284, 5747.310322761275, 5582.106862901936, 5805.854853628651, 5806.622569532967, 5806.912896306559, 5810.24466819315, 5090.516400941905, 5102.234664800272, 5113.488651134857, 5154.149565321717, 5155.561777248939, 20483.0, 20641.0, 20724.378268651966, 20759.273425430038, 20869.269575564933, 2129.3718353085724, 2130.286354071388, 2167.631502644554, 2213.3249358945513, 2253.9123533642487, 12916.741665166644, 13011.479540818356, 13131.671708207372, 13352.532661356903, 13445.058845880792, 1782.3672596228282, 1782.8395444410066, 1786.285485805675, 1802.774653168354, 1844.7970723220435, 57510.7124464375, 59260.0, 59425.10862337112, 59601.403985960016, 59778.402215977716, 9581.0, 9863.80272394548, 9865.161461275728, 9885.357451765612, 9939.353941993748, 12806.103572750004, 12916.870831291755, 12926.935365323301, 12946.352682366032, 12947.482100716054, 7389.965404843326, 7399.48203625741, 7442.7395041173295, 7444.478886478003, 7541.962290188514, 7136.179339375934, 7154.964225178841, 7184.0, 7205.315495027964, 7214.567125972353, 5850.64896106235, 5892.292924849045, 5900.704964751745, 5921.111833224964, 5961.254843144607, 1672.481530725479, 1689.4931520543787, 1715.5025042737716, 1743.755874177616, 1763.5414792153877, 895.4627223665879, 922.5202894494826, 987.3828856964398, 1073.7906108308962, 1077.4881931082673, 14950.177740224392, 15506.844931550766, 16418.0, 16592.917035414746, 16755.664886702198, 933.5892207428709, 940.8024314893812, 945.3760518058049, 985.9309848310693, 986.0977205856561, 9662.227021838236, 9849.0, 9963.402195868272, 10047.59809607622, 10096.545655445523, 506893.0, 508186.0, 515321.0, 527215.0, 533111.0, 67845.3215467849, 68627.65686171537, 69255.30744692488, 69887.65056174788, 70479.29520704727, 1128.323006196292, 1130.0902773267435, 1137.1300954769608, 1147.4148184426003, 1150.8791576884473, 15143.772843407138, 15176.468823591122, 15441.845581544041, 15625.68748625044, 15850.603734906259, 34145.0, 34262.0, 34429.48355774715, 34475.0, 34496.827515862264, 43972.0, 44240.55759442493, 44535.33197002112, 44961.62737862859, 46153.0, 9662.888767791794, 9739.853902191331, 9781.266405019795, 9797.21622270231, 9799.902000979899, 12980.598589261017, 16519.752203716715, 17432.82567174312, 17537.0, 17766.822331777035, 11879.76240475203, 13219.9424046078, 13939.581812545639, 14056.78914816299, 14179.858201417854, 14314.284285785878, 14347.0, 14512.056716313828, 14763.409463621334, 15194.696106078038, 14824.40702371893, 15177.178738551445, 15287.235638218268, 15463.768043479582, 15792.684146317239, 9342.486163261086, 9444.433333999981, 9480.383775054599, 9760.365576237522, 9795.0, 10229.181665466596, 10256.38461692301, 12465.813012804996, 14215.857841421453, 14244.572662820132], "unit": "score", "name": "jquery.html", "value": 8887.931070773193}, {"lowerIsBetter": false, "replicates": [1319.0, 1434.526606219947, 1436.102435977514, 1565.4520917678815, 1756.8243175682442, 6873.828154296145, 7623.961880190598, 7718.729844455445, 7726.188750181221, 7728.07263128424, 7531.887021694679, 7769.223077692228, 7770.9611451942665, 7787.883181752264, 7790.766277011685, 1767.7613522174536, 1796.6047469556743, 2165.9891700541516, 2204.8456608037445, 2213.9335819925427, 2232.687423760675, 2241.013953860296, 2241.293992392399, 2242.5739109569213, 2243.181238847817, 2154.7522034965946, 2182.4652960024814, 2184.421128400975, 2185.792349726781, 2186.857854239477, 2176.0534167637047, 2184.9184653596494, 2207.7461091974387, 2211.7235345581803, 2216.612092883739, 2207.4591725027312, 2240.6078936186104, 2259.706238189025, 2262.049939025613, 2286.942826429336, 7618.466707330434, 7720.536767793894, 7730.7680769577, 7731.0, 7747.147813740484, 4553.658475614309, 4594.563516465931, 4596.563326484012, 4597.770111494412, 4611.815527378901, 2989.282572182682, 3004.2339203503184, 3011.051518771602, 3017.5021121514924, 3157.3369592385397, 954.079313462512, 956.3114557518576, 982.5922242269521, 1016.3850870223556, 1022.3405903192399, 10847.67456976292, 10849.728756781144, 10877.292975956616, 10891.83662245067, 10902.291351062155, 4075.2053349597136, 4092.4066010428323, 4173.08192197716, 4179.707420480538, 4179.937300940488, 9214.815703685888, 9226.30802689801, 9228.031056738946, 9242.445453272825, 9247.445153290824, 4597.540245975376, 4626.144163329727, 4631.814727410933, 4635.466921304026, 4640.512746161605, 4194.580541945842, 4206.0, 4217.156568686275, 4231.301835197158, 4240.427542281815, 3211.4861622140347, 3260.0382887048377, 3505.8422370993367, 3527.3827080260853, 3551.8224088795973, 1098.8956049175315, 1110.1063643766684, 1118.9216754827246, 1121.2991880074953, 1126.0934947367284, 960.7790208252142, 1165.5745652836654, 1225.2893321873291, 1231.9815202771965, 1268.2010333489843, 7125.42996560274, 7126.928730712627, 7149.213586505481, 7175.067241258603, 7187.5687458752645, 1073.355986408149, 1078.7087486378791, 1084.0947808579767, 1092.2736380307165, 1102.0412241349916, 5665.66006039639, 5791.131330300404, 5803.622764520293, 5812.941870581325, 5814.622049566765, 8881.689140880038, 9416.869975602971, 9431.575579098957, 9439.76400589977, 9450.291228157916, 9365.250779937589, 9388.2020028297, 9389.295802814817, 9423.48170850596, 9427.198688111588, 7145.606991615511, 7168.0681511402145, 7169.426445884421, 7192.92807071933, 7196.64016799158, 7073.964630176816, 7127.251638578082, 7150.034745309316, 7162.7493037743425, 7165.498415110892, 7905.56519391416, 7975.481593696391, 8030.87953680707, 8190.713325033834, 8217.630206640595, 8225.424220304521, 8279.172082791914, 8287.0, 8302.086770455366, 8358.247757701836, 6466.224053113657, 6636.13730215069, 6644.33556644351, 6659.966700166468, 6692.498062645418, 6690.19717633887, 6809.727610895508, 7092.0, 7290.890636640561, 7292.270772922878, 6927.480438967198, 6984.092068031327, 6986.406155476841, 7103.289671032855, 7135.714571417086, 6891.414229790524, 6894.517383783087, 6915.239323674291, 6930.861382772216, 6956.513044086865, 6945.965270173617, 6996.755113571001, 6997.0, 6998.685059172248, 7009.404200643003, 6250.874982500415, 6384.744610215539, 6391.616503009835, 6393.904091438539, 6399.04014397844, 6346.174997250328, 6484.4812415005745, 6486.805395838132, 6494.6103233806125, 6497.967510162419], "unit": "score", "name": "mootools.html", "value": 4398.570930675513}, {"lowerIsBetter": false, "replicates": [332.3253794796563, 335.5202061052695, 337.22270167264475, 338.07367812194593, 344.83275611328503, 5721.0, 5827.213326200962, 5862.149988251706, 5877.70611469427, 5918.556108291879, 5597.580181486384, 5788.073908174693, 5791.82624521264, 5808.59339846211, 5829.5919285650025, 4507.932381014271, 4510.526394728556, 4515.864524064284, 4523.683342166049, 4526.936170000047, 4299.968007678166, 4383.167198232326, 4385.144896745136, 4478.373027776114, 4517.503074661785, 4422.889427764299, 4428.1807865544815, 4429.77851107445, 4440.4893437254805, 4443.111377724452, 4415.602595766396, 4417.204903117422, 4420.651701231123, 4421.182081314951, 4464.107178564268, 3919.3729003359613, 3922.6469617734256, 3931.0761970936805, 3942.940855887164, 3963.7819919904396, 5425.945740542584, 5560.944390556083, 5592.496675299242, 5600.383957764642, 5608.467195616451, 5167.534921857051, 5175.482451754794, 5178.922316165261, 5188.143956247215, 5192.402873669538, 5910.704464776744, 6210.627362358227, 6218.657973811484, 6220.377962203744, 6226.097215903714, 4186.434831297796, 4230.259704551691, 4254.553271906467, 4266.701330906838, 4297.720648157891, 6358.427741503287, 6445.677716114175, 6453.419192272718, 6453.967730161319, 6455.2253729552285, 4403.295472724413, 4414.668899832525, 4415.2935530315, 4416.602505774432, 4417.536158703354, 4316.1583491219535, 4438.090191510732, 4448.666350023761, 4451.421315229064, 4452.755098469615, 5156.278121063054, 5163.535281824654, 5171.3277273954145, 5176.1459359206065, 5190.584753219809, 6132.816015519452, 6138.447539721446, 6185.690715464209, 6194.628322300677, 6202.1937148171355, 1532.5632194824423, 1557.7196104701263, 1562.7733978573044, 1572.2924683892295, 1573.2841557091497, 1052.1057101463696, 1052.6263176572359, 1058.9947050264852, 1059.3590877519143, 1078.9730256743644, 5892.234009578728, 5902.409759024149, 5904.498117659961, 5910.34986151523, 5915.024021036566, 6381.1066450696935, 6382.744690212339, 6383.9361606383345, 6385.425311721874, 6387.073874288295, 5883.764649413975, 5884.2350494436305, 5889.911651325147, 5890.617109887844, 5890.969080410911, 3372.629010808809, 3457.2048428861, 3457.6715212054814, 3467.5318831957866, 3472.2187507811045, 6649.335066493312, 6666.466682665375, 6669.333066693292, 6776.0, 6776.69504872282, 6657.1012913257055, 6664.633445160466, 6668.966310221924, 6672.86654266912, 6673.265940746514, 6556.836079097965, 6716.059751634775, 6725.529212955046, 6742.0224067509935, 6742.359475849787, 6176.28972668131, 6325.430711236011, 6444.9033264502, 6445.6454894981225, 6446.774362897276, 6184.81445556634, 6291.370862913672, 6352.205974253218, 6352.650604216812, 6375.840603984938, 6690.632015239208, 6697.229818570927, 6703.765368212089, 6704.396604305636, 6707.798766037026, 4251.511076226274, 4391.912161756683, 4443.0, 4444.155610434136, 4445.444319460067, 6369.649669268049, 6384.6169229846355, 6390.616563006234, 6393.1369265148605, 6394.7442102317245, 6208.720607572761, 6214.875702486015, 6214.937850621436, 6217.222847144107, 6221.657808820558, 6081.817545473643, 6155.169052177898, 6198.349173336735, 6198.81403557894, 6206.379362063757, 6119.8470038247615, 6161.784337548164, 6175.722092505758, 6187.226596675416, 6191.907121393273, 6308.022256550242, 6308.779192728416, 6309.589876658002, 6312.4634406076, 6317.842053948688, 6007.429294217042, 6080.69596520181, 6156.815295541141, 6168.475679567287, 6172.598781079216], "unit": "score", "name": "prototype.html", "value": 4683.675840967895}, {"lowerIsBetter": false, "replicates": [738.8596166728321, 813.9511629302247, 828.6064119543214, 851.5912362066208, 866.8266346730653, 13505.0, 13711.38298776555, 13728.862711372883, 13742.312884355792, 13757.449702011907, 12669.176503527264, 12766.617001489947, 12770.80843787344, 14493.49272775453, 14766.63083422915, 830.646975035609, 833.9700469919644, 834.0783434305097, 836.5984327522792, 838.0237023867187, 824.5794644731201, 825.6243409248777, 825.6614787936948, 825.9628316725732, 826.9710560130396, 856.9957150214271, 857.5755001274347, 857.9184977427135, 862.4006315610653, 864.9567521623925, 855.9315254779602, 858.1289990659485, 864.7362554420899, 865.523961820996, 865.5412631305418, 639.9488040956759, 641.6470940982441, 641.8170821315903, 641.8170821315949, 642.1267076775628, 13646.931765341109, 13800.93099534506, 13801.51694690681, 13804.378802953892, 13814.0, 12171.756564868652, 12341.382930853511, 12458.937705311506, 12476.75046499074, 12623.747525049539, 10212.744681383025, 10376.16990640747, 10473.47632618366, 10676.946615266874, 11031.448427578589, 3591.8024508652015, 3668.834902429397, 3685.7972811495624, 3686.4654625079484, 3689.6494832990825, 41374.58625413737, 45017.324740128926, 48728.0, 54182.18726719063, 54198.45801541973, 10177.898221017844, 10281.897181028095, 10311.432871192006, 10744.73138171552, 10806.135509159405, 18109.36617218391, 18110.366137185134, 18140.365087222148, 18148.36480723195, 18155.636887262444, 7351.742689005859, 7482.326590606872, 7530.058742657168, 7729.265719756614, 7807.0, 4314.115606300701, 4375.343698445195, 4391.187630288407, 4400.69195156336, 4434.5343738907595, 3112.5486804413686, 3143.8899638512544, 3145.2294187924103, 3147.779655424144, 3148.98425507871, 514.8918727067282, 538.8572028412474, 542.6879544262065, 555.3030946162621, 559.7732918168149, 571.711285800668, 607.6202373516552, 693.3111790246194, 711.3206887422602, 719.0472623773521, 8596.8710469343, 8618.870716939251, 8724.127587241352, 8810.19067648785, 9033.412828166149, 662.7481557008402, 703.736098962889, 706.7773651299824, 706.9823254418578, 716.3194964783477, 3988.4814974053766, 4000.4199391088127, 4296.914061718748, 4477.9328310074725, 4508.864734057984, 49539.0, 50999.235011474855, 51827.74086129545, 52103.73948130235, 52224.738876305375, 49190.50809491931, 49240.261396079084, 49494.50505494899, 49549.25676114861, 49691.25463118056, 41601.37597936033, 44699.10601788011, 50990.0, 50999.235011474855, 51417.0, 8592.3555733319, 8789.560521974003, 8945.883356392258, 8973.147550982647, 9264.53677316145, 30919.69080309258, 33796.3240735179, 34696.306073878884, 34935.47596786101, 35333.823330883184, 35479.645203548665, 40529.594704053765, 41863.58136418597, 42221.0, 42233.57766422296, 11108.61119860801, 11133.832992504957, 11135.721606959567, 11142.331460112418, 11402.973732364127, 12411.503539858666, 12548.937255313665, 12632.873671263538, 13213.933930330288, 13225.537106201236, 11242.437878106226, 11266.324020558794, 11553.133514986577, 11650.70873228176, 11709.355985420883, 11666.394064205166, 11892.464839082435, 11955.103367247666, 12364.0, 12391.566295179626, 11394.772104558027, 11689.415529223335, 11825.82261266099, 11863.703407414883, 12088.879111209128, 9471.479068651015, 9657.372270802376, 9664.323497355117, 9677.242588635207, 9771.361732332089, 8968.417052891542, 9088.863667044867, 9959.153471954965, 9987.550560224952, 10027.598896044368], "unit": "score", "name": "yui.html", "value": 6383.664323433454}], "name": "dromaeo_css", "value": 6895.552334358599}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 112.0, 105.0, 111.0, 111.0, 110.0, 101.0, 111.0, 111.0, 102.0], "unit": "score", "name": "ai-astar", "value": 108.4}, {"lowerIsBetter": true, "replicates": [157.0, 132.0, 151.0, 164.0, 124.0, 148.0, 151.0, 125.0, 167.0, 131.0], "unit": "score", "name": "audio-beat-detection", "value": 145.0}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 161.0, 162.0, 161.0, 160.0, 160.0, 161.0, 160.0, 163.0], "unit": "score", "name": "audio-dft", "value": 161.3}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 88.0, 94.0, 70.0, 71.0, 73.0, 97.0, 92.0, 71.0], "unit": "score", "name": "audio-fft", "value": 80.0}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 141.0, 95.0, 97.0, 96.0, 141.0, 95.0, 97.0, 140.0], "unit": "score", "name": "audio-oscillator", "value": 109.4}, {"lowerIsBetter": true, "replicates": [109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [59.0, 60.0, 58.0, 58.0, 59.0, 65.0, 59.0, 71.0, 57.0, 58.0], "unit": "score", "name": "json-parse-financial", "value": 60.4}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 68.0, 73.0, 67.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 86.0, 87.0, 87.0, 88.0, 88.0, 94.0, 88.0, 89.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.8}, {"lowerIsBetter": true, "replicates": [122.0, 117.0, 120.0, 118.0, 119.0, 119.0, 130.0, 122.0, 122.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.6}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 158.0, 157.0, 158.0, 146.0, 164.0, 156.0, 155.0, 158.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 154.4}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 72.0, 69.0, 68.0, 68.0, 69.0, 70.0, 70.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.5}], "name": "kraken", "value": 1514.7}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.7008217340265], "unit": "score", "name": "Richards", "value": 210.50582599999998}, {"lowerIsBetter": false, "replicates": [191.5341888527102], "unit": "score", "name": "DeltaBlue", "value": 345.2020779999999}, {"lowerIsBetter": false, "replicates": [279.87685418415896], "unit": "score", "name": "Encrypt", "value": 951.0647130000004}, {"lowerIsBetter": false, "replicates": [5251.308900523561], "unit": "score", "name": "Decrypt", "value": 50.68850548354939}, {"lowerIsBetter": false, "replicates": [2024.2914979757086], "unit": "score", "name": "RayTrace", "value": 365.55456600000014}, {"lowerIsBetter": false, "replicates": [804.0160642570281], "unit": "score", "name": "Earley", "value": 828.9175174825176}, {"lowerIsBetter": false, "replicates": [12180.722891566265], "unit": "score", "name": "Boyer", "value": 54.714568743818035}, {"lowerIsBetter": false, "replicates": [26051.28205128205], "unit": "score", "name": "RegExp", "value": 34.96891240157479}, {"lowerIsBetter": false, "replicates": [633.9455351488284], "unit": "score", "name": "Splay", "value": 128.54574325674324}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19592.556334377514}]} 22:05:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "19592.56"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1514.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6895.55"}}} 22:05:39 INFO - Return code: 0 22:05:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:05:39 INFO - # TBPL SUCCESS # 22:05:39 INFO - Running post-action listener: _resource_record_post_action 22:05:39 INFO - Running post-run listener: _resource_record_post_run 22:05:40 INFO - Total resource usage - Wall time: 1231s; CPU: 13.0%; Read bytes: 598016; Write bytes: 318304256; Read time: 108; Write time: 559992 22:05:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 1503232; Read time: 0; Write time: 68 22:05:40 INFO - run-tests - Wall time: 1216s; CPU: 13.0%; Read bytes: 598016; Write bytes: 316801024; Read time: 108; Write time: 559924 22:05:40 INFO - Running post-run listener: _upload_blobber_files 22:05:40 INFO - Blob upload gear active. 22:05:40 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:05:40 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:05:40 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 22:05:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 22:05:41 INFO - (blobuploader) - INFO - Open directory for files ... 22:05:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 22:05:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:05:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:05:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:05:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:05:42 INFO - (blobuploader) - INFO - Done attempting. 22:05:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:05:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:05:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:05:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:05:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:05:42 INFO - (blobuploader) - INFO - Done attempting. 22:05:42 INFO - (blobuploader) - INFO - Iteration through files over. 22:05:42 INFO - Return code: 0 22:05:42 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:05:42 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:05:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3555a6d3b05d1df561f1e7fa17493e7a5d775cb6a3f3f323ad1717b00ba88f1ace1a1c11d338904cc2b110b275578c5b6592dcf620627aea00c44f02072fb108", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66a82ec776c10d092bf9c7e54601ecb170506c23b36761e946a1f60e2ef1611acf28acfc0d26bcb8da3b1cd4f77e6633a7db97f186b5d682854631bdcb546ae8"} 22:05:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:05:42 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:05:42 INFO - Contents: 22:05:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3555a6d3b05d1df561f1e7fa17493e7a5d775cb6a3f3f323ad1717b00ba88f1ace1a1c11d338904cc2b110b275578c5b6592dcf620627aea00c44f02072fb108", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66a82ec776c10d092bf9c7e54601ecb170506c23b36761e946a1f60e2ef1611acf28acfc0d26bcb8da3b1cd4f77e6633a7db97f186b5d682854631bdcb546ae8"} 22:05:42 INFO - Copying logs to upload dir... 22:05:42 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1276.149227 ========= master_lag: 1.88 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-01-05 22:05:44.784598) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-05 22:05:44.785833) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/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-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3555a6d3b05d1df561f1e7fa17493e7a5d775cb6a3f3f323ad1717b00ba88f1ace1a1c11d338904cc2b110b275578c5b6592dcf620627aea00c44f02072fb108", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66a82ec776c10d092bf9c7e54601ecb170506c23b36761e946a1f60e2ef1611acf28acfc0d26bcb8da3b1cd4f77e6633a7db97f186b5d682854631bdcb546ae8"} build_url:https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011428 build_url: 'https://queue.taskcluster.net/v1/task/Ja9URyUOQayZhNpLeyLiAg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3555a6d3b05d1df561f1e7fa17493e7a5d775cb6a3f3f323ad1717b00ba88f1ace1a1c11d338904cc2b110b275578c5b6592dcf620627aea00c44f02072fb108", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66a82ec776c10d092bf9c7e54601ecb170506c23b36761e946a1f60e2ef1611acf28acfc0d26bcb8da3b1cd4f77e6633a7db97f186b5d682854631bdcb546ae8"}' ========= master_lag: 0.84 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-05 22:05:45.640831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-01-05 22:05:45.641229) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (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-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1452058557.407476-840313226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 1.73 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-01-05 22:05:47.372001) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-01-05 22:05:47.372313) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-01-05 22:05:51.038359) ========= ========= Total master_lag: 4.54 =========