builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1453383373.43 results: success (0) buildid: 20160121030208 builduid: 37a9242cc6de40529d31a6695854a5c0 revision: 977d78a8dd78afbc0153d37fd9887c3a200dce6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.434732) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.435425) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.435729) ========= 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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006468 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.458679) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.458969) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.476164) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.476405) ========= 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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004670 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.528643) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.528902) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.529233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.529500) ========= 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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-21 05:36:13-- 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% 31.5M=0s 2016-01-21 05:36:13 (31.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106382 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.650228) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.650538) ========= 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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021089 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.700576) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:13.700943) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 977d78a8dd78afbc0153d37fd9887c3a200dce6a --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 977d78a8dd78afbc0153d37fd9887c3a200dce6a --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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-21 05:36:14,121 truncating revision to first 12 chars 2016-01-21 05:36:14,121 Setting DEBUG logging. 2016-01-21 05:36:14,121 attempt 1/10 2016-01-21 05:36:14,122 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/977d78a8dd78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-21 05:36:14,505 unpacking tar archive at: mozilla-central-977d78a8dd78/testing/mozharness/ program finished with exit code 0 elapsedTime=0.670591 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:14.385416) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:14.385818) ========= echo 977d78a8dd78afbc0153d37fd9887c3a200dce6a in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'977d78a8dd78afbc0153d37fd9887c3a200dce6a'] 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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 977d78a8dd78afbc0153d37fd9887c3a200dce6a program finished with exit code 0 elapsedTime=0.003394 script_repo_revision: '977d78a8dd78afbc0153d37fd9887c3a200dce6a' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:14.433008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:14.433308) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:14.443211) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:14.443523) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/977d78a8dd78afbc0153d37fd9887c3a200dce6a ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-21 05:36:14.443978) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-01-21 05:36:14.444264) ========= /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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:36:14 INFO - MultiFileLogger online at 20160121 05:36:14 in /builds/slave/test-pgo 05:36:14 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 05:36:14 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:36:14 INFO - {'append_to_log': False, 05:36:14 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:36:14 INFO - 'blob_upload_branch': 'Firefox', 05:36:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:36:14 INFO - 'branch': 'Firefox', 05:36:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:36:14 INFO - 'config_files': ('talos/linux_config.py',), 05:36:14 INFO - 'default_actions': ('clobber', 05:36:14 INFO - 'read-buildbot-config', 05:36:14 INFO - 'download-and-extract', 05:36:14 INFO - 'populate-webroot', 05:36:14 INFO - 'create-virtualenv', 05:36:14 INFO - 'install', 05:36:14 INFO - 'run-tests'), 05:36:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:36:14 INFO - 'download_minidump_stackwalk': True, 05:36:14 INFO - 'download_symbols': 'ondemand', 05:36:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:36:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:36:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:36:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:36:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:36:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:36:14 INFO - 'installer_path': 'installer.exe', 05:36:14 INFO - 'log_level': 'info', 05:36:14 INFO - 'log_name': 'talos', 05:36:14 INFO - 'log_to_console': True, 05:36:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:36:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:36:14 INFO - 'opt_config_files': (), 05:36:14 INFO - 'pip_index': False, 05:36:14 INFO - 'sps_profile': False, 05:36:14 INFO - 'sps_profile_interval': 0, 05:36:14 INFO - 'suite': 'dromaeojs', 05:36:14 INFO - 'system_bits': '32', 05:36:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:36:14 INFO - 'title': 'talos-linux64-ix-056', 05:36:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:36:14 INFO - 'use_talos_json': True, 05:36:14 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:36:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:36:14 INFO - 'work_dir': 'build'} 05:36:14 INFO - ##### 05:36:14 INFO - ##### Running clobber step. 05:36:14 INFO - ##### 05:36:14 INFO - Running pre-action listener: _resource_record_pre_action 05:36:14 INFO - Running main action method: clobber 05:36:14 INFO - rmtree: /builds/slave/test-pgo/build 05:36:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:36:15 INFO - Running post-action listener: _resource_record_post_action 05:36:15 INFO - ##### 05:36:15 INFO - ##### Running read-buildbot-config step. 05:36:15 INFO - ##### 05:36:15 INFO - Running pre-action listener: _resource_record_pre_action 05:36:15 INFO - Running main action method: read_buildbot_config 05:36:15 INFO - Using buildbot properties: 05:36:15 INFO - { 05:36:15 INFO - "project": "", 05:36:15 INFO - "product": "firefox", 05:36:15 INFO - "script_repo_revision": "production", 05:36:15 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:36:15 INFO - "repository": "", 05:36:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 05:36:15 INFO - "buildid": "20160121030208", 05:36:15 INFO - "stage_platform": "linux64-pgo", 05:36:15 INFO - "basedir": "/builds/slave/test-pgo", 05:36:15 INFO - "nightly_build": "True", 05:36:15 INFO - "buildnumber": 315, 05:36:15 INFO - "slavename": "talos-linux64-ix-056", 05:36:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:36:15 INFO - "platform": "ubuntu64_hw", 05:36:15 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 05:36:15 INFO - "branch": "mozilla-central", 05:36:15 INFO - "repo_path": "mozilla-central", 05:36:15 INFO - "revision": "977d78a8dd78afbc0153d37fd9887c3a200dce6a", 05:36:15 INFO - "pgo_build": "True", 05:36:15 INFO - "builduid": "37a9242cc6de40529d31a6695854a5c0", 05:36:15 INFO - "slavebuilddir": "test" 05:36:15 INFO - } 05:36:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 05:36:15 INFO - Running post-action listener: _resource_record_post_action 05:36:15 INFO - ##### 05:36:15 INFO - ##### Running download-and-extract step. 05:36:15 INFO - ##### 05:36:15 INFO - Running pre-action listener: _resource_record_pre_action 05:36:15 INFO - Running main action method: download_and_extract 05:36:15 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:36:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:36:15 INFO - https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:36:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/test_packages.json 05:36:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/test_packages.json 05:36:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:36:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:36:25 INFO - Downloaded 1448 bytes. 05:36:25 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:36:25 INFO - Using the following test package requirements: 05:36:25 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:36:25 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:36:25 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:36:25 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:36:25 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:36:25 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:36:25 INFO - u'jsshell-linux-x86_64.zip'], 05:36:25 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:36:25 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:36:25 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:36:25 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:36:25 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:36:25 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:36:25 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:36:25 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:36:25 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:36:25 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 05:36:25 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 05:36:25 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:36:25 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:36:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:36:25 INFO - https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:36:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:36:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:36:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/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 05:36:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/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 05:36:28 INFO - Downloaded 22486744 bytes. 05:36:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:36:28 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 05:36:29 INFO - Return code: 0 05:36:29 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 05:36:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:36:29 INFO - https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:36:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:36:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 05:36:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/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 05:36:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/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 05:36:42 INFO - Downloaded 22486744 bytes. 05:36:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:36:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 05:36:43 INFO - Return code: 0 05:36:43 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')]} 05:36:43 INFO - https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:36:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:36:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 05:36:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/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 05:36:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/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 05:36:45 INFO - Downloaded 10705515 bytes. 05:36:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 05:36:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 05:36:45 INFO - Return code: 0 05:36:45 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')]} 05:36:45 INFO - https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:36:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:36:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:36:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/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 05:36:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vo_nqZB8SVSaKYqve199EA/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 05:36:47 INFO - Downloaded 51198092 bytes. 05:36:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:36:47 INFO - mkdir: /builds/slave/test-pgo/properties 05:36:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:36:47 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:36:47 INFO - Contents: 05:36:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 05:36:47 INFO - Running post-action listener: _resource_record_post_action 05:36:47 INFO - Running post-action listener: set_extra_try_arguments 05:36:47 INFO - ##### 05:36:47 INFO - ##### Running populate-webroot step. 05:36:47 INFO - ##### 05:36:47 INFO - Running pre-action listener: _resource_record_pre_action 05:36:47 INFO - Running main action method: populate_webroot 05:36:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:36:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:36:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:36:47 INFO - '--tppagecycles', 05:36:47 INFO - '7'], 05:36:47 INFO - 'tests': ['tsvgm']}}, 05:36:47 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:36:47 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:36:47 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:36:47 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:36:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:36:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:47 INFO - 'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:36:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:47 INFO - 'tests': ['damp', 'tps']}, 05:36:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:47 INFO - 'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['damp', 'tps']}, 05:36:47 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:36:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:36:47 INFO - 'other': {'tests': ['a11yr', 05:36:47 INFO - 'ts_paint', 05:36:47 INFO - 'tpaint', 05:36:47 INFO - 'sessionrestore', 05:36:47 INFO - 'sessionrestore_no_auto_restore']}, 05:36:47 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['a11yr', 05:36:47 INFO - 'ts_paint', 05:36:47 INFO - 'tpaint', 05:36:47 INFO - 'sessionrestore', 05:36:47 INFO - 'sessionrestore_no_auto_restore']}, 05:36:47 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['a11yr', 05:36:47 INFO - 'ts_paint', 05:36:47 INFO - 'tpaint', 05:36:47 INFO - 'sessionrestore', 05:36:47 INFO - 'sessionrestore_no_auto_restore']}, 05:36:47 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['a11yr', 05:36:47 INFO - 'ts_paint', 05:36:47 INFO - 'tpaint', 05:36:47 INFO - 'sessionrestore', 05:36:47 INFO - 'sessionrestore_no_auto_restore']}, 05:36:47 INFO - 'other_l64': {'tests': ['a11yr', 05:36:47 INFO - 'ts_paint', 05:36:47 INFO - 'tpaint', 05:36:47 INFO - 'sessionrestore', 05:36:47 INFO - 'sessionrestore_no_auto_restore']}, 05:36:47 INFO - 'other_nol64': {'tests': ['a11yr', 05:36:47 INFO - 'ts_paint', 05:36:47 INFO - 'tpaint', 05:36:47 INFO - 'sessionrestore', 05:36:47 INFO - 'sessionrestore_no_auto_restore']}, 05:36:47 INFO - 'svgr': {'tests': ['tsvgx', 05:36:47 INFO - 'tsvgr_opacity', 05:36:47 INFO - 'tart', 05:36:47 INFO - 'tscrollx', 05:36:47 INFO - 'cart']}, 05:36:47 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['tsvgx', 05:36:47 INFO - 'tsvgr_opacity', 05:36:47 INFO - 'tart', 05:36:47 INFO - 'tscrollx', 05:36:47 INFO - 'cart']}, 05:36:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:47 INFO - 'tests': ['tp5o']}, 05:36:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:47 INFO - 'talos_options': ['--e10s'], 05:36:47 INFO - 'tests': ['tp5o']}, 05:36:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:47 INFO - 'talos_options': ['--xperf_path', 05:36:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:36:47 INFO - 'tests': ['tp5n']}, 05:36:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:36:47 INFO - 'talos_options': ['--e10s', 05:36:47 INFO - '--xperf_path', 05:36:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:36:47 INFO - 'tests': ['tp5n']}}, 05:36:47 INFO - 'talos.zip': {'path': '', 05:36:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:36:47 INFO - Running post-action listener: _resource_record_post_action 05:36:47 INFO - ##### 05:36:47 INFO - ##### Running create-virtualenv step. 05:36:47 INFO - ##### 05:36:47 INFO - Running pre-action listener: _resource_record_pre_action 05:36:47 INFO - Running main action method: create_virtualenv 05:36:47 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:36:47 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 05:36:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:36:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:36:47 INFO - Using real prefix '/usr' 05:36:47 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:36:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:36:49 INFO - Installing pip.................done. 05:36:49 INFO - Return code: 0 05:36:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:36: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')]} 05:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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')]} 05:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:36:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 05:36:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:36:49 INFO - Using env: {'DISPLAY': ':0', 05:36:49 INFO - 'HOME': '/home/cltbld', 05:36:49 INFO - 'LANG': 'en_US.UTF-8', 05:36:49 INFO - 'LANGUAGE': 'en_US:en', 05:36:49 INFO - 'LOGNAME': 'cltbld', 05:36:49 INFO - 'MAIL': '/var/mail/cltbld', 05:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:49 INFO - 'MOZ_NO_REMOTE': '1', 05:36:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:36:49 INFO - 'NO_EM_RESTART': '1', 05:36:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:36:49 INFO - 'PWD': '/builds/slave/test-pgo', 05:36:49 INFO - 'SHELL': '/bin/bash', 05:36:49 INFO - 'SHLVL': '1', 05:36:49 INFO - 'TERM': 'linux', 05:36:49 INFO - 'TMOUT': '86400', 05:36:49 INFO - 'USER': 'cltbld', 05:36:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:49 INFO - '_': '/tools/buildbot/bin/python'} 05:36:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:49 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 05:36:49 INFO - Cleaning up... 05:36:49 INFO - Return code: 0 05:36:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:36: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')]} 05:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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')]} 05:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:36:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:36:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:36:49 INFO - Using env: {'DISPLAY': ':0', 05:36:49 INFO - 'HOME': '/home/cltbld', 05:36:49 INFO - 'LANG': 'en_US.UTF-8', 05:36:49 INFO - 'LANGUAGE': 'en_US:en', 05:36:49 INFO - 'LOGNAME': 'cltbld', 05:36:49 INFO - 'MAIL': '/var/mail/cltbld', 05:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:49 INFO - 'MOZ_NO_REMOTE': '1', 05:36:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:36:49 INFO - 'NO_EM_RESTART': '1', 05:36:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:36:49 INFO - 'PWD': '/builds/slave/test-pgo', 05:36:49 INFO - 'SHELL': '/bin/bash', 05:36:49 INFO - 'SHLVL': '1', 05:36:49 INFO - 'TERM': 'linux', 05:36:49 INFO - 'TMOUT': '86400', 05:36:49 INFO - 'USER': 'cltbld', 05:36:49 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:49 INFO - '_': '/tools/buildbot/bin/python'} 05:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:50 INFO - Downloading/unpacking psutil>=0.7.1 05:36:50 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 05:36:50 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 05:36:50 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 05:36:50 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 05:36:50 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 05:36:50 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 05:36:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:36:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:36:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:36:52 INFO - Installing collected packages: psutil 05:36:52 INFO - Running setup.py install for psutil 05:36:52 INFO - building 'psutil._psutil_linux' extension 05:36:52 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 05:36:52 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 05:36:52 INFO - building 'psutil._psutil_posix' extension 05:36:52 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 05:36:52 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 05:36:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:36:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:36:52 INFO - Successfully installed psutil 05:36:52 INFO - Cleaning up... 05:36:52 INFO - Return code: 0 05:36:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:36:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:36:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:36:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:36:52 INFO - Using env: {'DISPLAY': ':0', 05:36:52 INFO - 'HOME': '/home/cltbld', 05:36:52 INFO - 'LANG': 'en_US.UTF-8', 05:36:52 INFO - 'LANGUAGE': 'en_US:en', 05:36:52 INFO - 'LOGNAME': 'cltbld', 05:36:52 INFO - 'MAIL': '/var/mail/cltbld', 05:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:52 INFO - 'MOZ_NO_REMOTE': '1', 05:36:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:36:52 INFO - 'NO_EM_RESTART': '1', 05:36:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:36:52 INFO - 'PWD': '/builds/slave/test-pgo', 05:36:52 INFO - 'SHELL': '/bin/bash', 05:36:52 INFO - 'SHLVL': '1', 05:36:52 INFO - 'TERM': 'linux', 05:36:52 INFO - 'TMOUT': '86400', 05:36:52 INFO - 'USER': 'cltbld', 05:36:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:52 INFO - '_': '/tools/buildbot/bin/python'} 05:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:36:52 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 05:36:52 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 05:36:52 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 05:36:52 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 05:36:52 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 05:36:52 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 05:36:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:36:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:36:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:36:54 INFO - Installing collected packages: mozsystemmonitor 05:36:54 INFO - Running setup.py install for mozsystemmonitor 05:36:54 INFO - Successfully installed mozsystemmonitor 05:36:54 INFO - Cleaning up... 05:36:54 INFO - Return code: 0 05:36:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:36:54 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')]} 05:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:54 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')]} 05:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:36:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:36:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:36:54 INFO - Using env: {'DISPLAY': ':0', 05:36:54 INFO - 'HOME': '/home/cltbld', 05:36:54 INFO - 'LANG': 'en_US.UTF-8', 05:36:54 INFO - 'LANGUAGE': 'en_US:en', 05:36:54 INFO - 'LOGNAME': 'cltbld', 05:36:54 INFO - 'MAIL': '/var/mail/cltbld', 05:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:54 INFO - 'MOZ_NO_REMOTE': '1', 05:36:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:36:54 INFO - 'NO_EM_RESTART': '1', 05:36:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:36:54 INFO - 'PWD': '/builds/slave/test-pgo', 05:36:54 INFO - 'SHELL': '/bin/bash', 05:36:54 INFO - 'SHLVL': '1', 05:36:54 INFO - 'TERM': 'linux', 05:36:54 INFO - 'TMOUT': '86400', 05:36:54 INFO - 'USER': 'cltbld', 05:36:54 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:54 INFO - '_': '/tools/buildbot/bin/python'} 05:36:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:54 INFO - Downloading/unpacking blobuploader==1.2.4 05:36: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 05:36: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 05:36: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 05:36: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 05:36: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 05:36: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 05:36:56 INFO - Downloading blobuploader-1.2.4.tar.gz 05:36:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:36:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:36:56 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 05:36:56 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 05:36:56 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 05:36:56 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 05:36:56 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 05:36:56 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 05:36:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:36:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:36:57 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 05:36:57 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 05:36:57 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 05:36:57 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 05:36:57 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 05:36:57 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 05:36:57 INFO - Downloading docopt-0.6.1.tar.gz 05:36:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:36:57 INFO - Installing collected packages: blobuploader, requests, docopt 05:36:57 INFO - Running setup.py install for blobuploader 05:36:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:36:57 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:36:57 INFO - Running setup.py install for requests 05:36:57 INFO - Running setup.py install for docopt 05:36:58 INFO - Successfully installed blobuploader requests docopt 05:36:58 INFO - Cleaning up... 05:36:58 INFO - Return code: 0 05:36:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:36: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')]} 05:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:36:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36: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')]} 05:36:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:36:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:36:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:36:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 05:36:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 05:36:58 INFO - Using env: {'DISPLAY': ':0', 05:36:58 INFO - 'HOME': '/home/cltbld', 05:36:58 INFO - 'LANG': 'en_US.UTF-8', 05:36:58 INFO - 'LANGUAGE': 'en_US:en', 05:36:58 INFO - 'LOGNAME': 'cltbld', 05:36:58 INFO - 'MAIL': '/var/mail/cltbld', 05:36:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:58 INFO - 'MOZ_NO_REMOTE': '1', 05:36:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:36:58 INFO - 'NO_EM_RESTART': '1', 05:36:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:36:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:36:58 INFO - 'PWD': '/builds/slave/test-pgo', 05:36:58 INFO - 'SHELL': '/bin/bash', 05:36:58 INFO - 'SHLVL': '1', 05:36:58 INFO - 'TERM': 'linux', 05:36:58 INFO - 'TMOUT': '86400', 05:36:58 INFO - 'USER': 'cltbld', 05:36:58 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:36:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:58 INFO - '_': '/tools/buildbot/bin/python'} 05:36:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:36:58 INFO - Running setup.py (path:/tmp/pip-mJs6cd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:36:58 INFO - Running setup.py (path:/tmp/pip-ywEehj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:36:58 INFO - Running setup.py (path:/tmp/pip-LoNMvT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:36:58 INFO - Running setup.py (path:/tmp/pip-TZScRe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:36:58 INFO - Running setup.py (path:/tmp/pip-790d_6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:36:58 INFO - Running setup.py (path:/tmp/pip-PzBkov-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:36:58 INFO - Running setup.py (path:/tmp/pip-AuHvRS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:36:58 INFO - Running setup.py (path:/tmp/pip-mMw4mf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:36:58 INFO - Running setup.py (path:/tmp/pip-ds4cHk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:36:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:36:58 INFO - Running setup.py (path:/tmp/pip-TEz33X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:36:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:36:59 INFO - Running setup.py (path:/tmp/pip-jBywIH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:36:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:36:59 INFO - Running setup.py (path:/tmp/pip-5xudd1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:36:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:36:59 INFO - Running setup.py (path:/tmp/pip-czeuDo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:36:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:36:59 INFO - Running setup.py (path:/tmp/pip-C8e65C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:36:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:36:59 INFO - Running setup.py (path:/tmp/pip-m29Zgy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:36:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:36:59 INFO - Running setup.py (path:/tmp/pip-9hLL1J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:36:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:36:59 INFO - Running setup.py (path:/tmp/pip-qf5IfL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:36:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:36:59 INFO - Running setup.py install for manifestparser 05:36:59 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:36:59 INFO - Running setup.py install for mozcrash 05:36:59 INFO - Running setup.py install for mozdebug 05:36:59 INFO - Running setup.py install for mozdevice 05:36:59 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:36:59 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:36:59 INFO - Running setup.py install for mozfile 05:37:00 INFO - Running setup.py install for mozhttpd 05:37:00 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Running setup.py install for mozinfo 05:37:00 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Running setup.py install for mozInstall 05:37:00 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Running setup.py install for mozleak 05:37:00 INFO - Running setup.py install for mozlog 05:37:00 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Running setup.py install for moznetwork 05:37:00 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Running setup.py install for mozprocess 05:37:00 INFO - Running setup.py install for mozprofile 05:37:00 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:37:00 INFO - Running setup.py install for mozrunner 05:37:00 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:37:01 INFO - Running setup.py install for mozscreenshot 05:37:01 INFO - Running setup.py install for moztest 05:37:01 INFO - Running setup.py install for mozversion 05:37:01 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:37:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:37:01 INFO - Cleaning up... 05:37:01 INFO - Return code: 0 05:37:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:37:01 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')]} 05:37:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:01 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')]} 05:37:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:37:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 05:37:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 05:37:01 INFO - Using env: {'DISPLAY': ':0', 05:37:01 INFO - 'HOME': '/home/cltbld', 05:37:01 INFO - 'LANG': 'en_US.UTF-8', 05:37:01 INFO - 'LANGUAGE': 'en_US:en', 05:37:01 INFO - 'LOGNAME': 'cltbld', 05:37:01 INFO - 'MAIL': '/var/mail/cltbld', 05:37:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:01 INFO - 'MOZ_NO_REMOTE': '1', 05:37:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:01 INFO - 'NO_EM_RESTART': '1', 05:37:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:37:01 INFO - 'PWD': '/builds/slave/test-pgo', 05:37:01 INFO - 'SHELL': '/bin/bash', 05:37:01 INFO - 'SHLVL': '1', 05:37:01 INFO - 'TERM': 'linux', 05:37:01 INFO - 'TMOUT': '86400', 05:37:01 INFO - 'USER': 'cltbld', 05:37:01 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:37:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:01 INFO - '_': '/tools/buildbot/bin/python'} 05:37:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:37:01 INFO - Running setup.py (path:/tmp/pip-kdHLTQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:37:01 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)) 05:37:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:37:01 INFO - Running setup.py (path:/tmp/pip-Cxy92C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:37:01 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)) 05:37:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:37:01 INFO - Running setup.py (path:/tmp/pip-YnjHOg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:37:01 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)) 05:37:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:37:01 INFO - Running setup.py (path:/tmp/pip-SpslhI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 05:37:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:37:01 INFO - Running setup.py (path:/tmp/pip-T005ur-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:37:01 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)) 05:37:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:37:01 INFO - Running setup.py (path:/tmp/pip-pFN4dk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:37:01 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)) 05:37:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:37:01 INFO - Running setup.py (path:/tmp/pip-Re6exU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:37:02 INFO - Running setup.py (path:/tmp/pip-T_GRuP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:37:02 INFO - Running setup.py (path:/tmp/pip-gAWd6v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:37:02 INFO - Running setup.py (path:/tmp/pip-Nrnx9L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:37:02 INFO - Running setup.py (path:/tmp/pip-ig9etu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:37:02 INFO - Running setup.py (path:/tmp/pip-Pyx2VM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:37:02 INFO - Running setup.py (path:/tmp/pip-6IUX5t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:37:02 INFO - Running setup.py (path:/tmp/pip-tHSvkp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:37:02 INFO - Running setup.py (path:/tmp/pip-4mRgr8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:37:02 INFO - Running setup.py (path:/tmp/pip-Zm_YcY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:37:02 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)) 05:37:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:37:02 INFO - Running setup.py (path:/tmp/pip-Y9TstE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:37:02 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)) 05:37:02 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)) 05:37:02 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)) 05:37:02 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)) 05:37: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 mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:37:02 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:37:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37:04 INFO - Downloading blessings-1.6.tar.gz 05:37:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:37:04 INFO - Installing collected packages: blessings 05:37:04 INFO - Running setup.py install for blessings 05:37:04 INFO - Successfully installed blessings 05:37:04 INFO - Cleaning up... 05:37:04 INFO - Return code: 0 05:37:04 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:37:04 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:37:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:37:04 INFO - Reading from file tmpfile_stdout 05:37:04 INFO - Current package versions: 05:37:04 INFO - argparse == 1.2.1 05:37:04 INFO - blessings == 1.6 05:37:04 INFO - blobuploader == 1.2.4 05:37:04 INFO - docopt == 0.6.1 05:37:04 INFO - manifestparser == 1.1 05:37:04 INFO - mozInstall == 1.12 05:37:04 INFO - mozcrash == 0.16 05:37:04 INFO - mozdebug == 0.1 05:37:04 INFO - mozdevice == 0.48 05:37:04 INFO - mozfile == 1.2 05:37:04 INFO - mozhttpd == 0.7 05:37:04 INFO - mozinfo == 0.9 05:37:04 INFO - mozleak == 0.1 05:37:04 INFO - mozlog == 3.1 05:37:04 INFO - moznetwork == 0.27 05:37:04 INFO - mozprocess == 0.22 05:37:04 INFO - mozprofile == 0.28 05:37:04 INFO - mozrunner == 6.11 05:37:04 INFO - mozscreenshot == 0.1 05:37:04 INFO - mozsystemmonitor == 0.0 05:37:04 INFO - moztest == 0.7 05:37:04 INFO - mozversion == 1.4 05:37:04 INFO - psutil == 3.1.1 05:37:04 INFO - requests == 1.2.3 05:37:04 INFO - wsgiref == 0.1.2 05:37:04 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:37: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')]} 05:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37: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')]} 05:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:37:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 05:37:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 05:37:04 INFO - Using env: {'DISPLAY': ':0', 05:37:04 INFO - 'HOME': '/home/cltbld', 05:37:04 INFO - 'LANG': 'en_US.UTF-8', 05:37:04 INFO - 'LANGUAGE': 'en_US:en', 05:37:04 INFO - 'LOGNAME': 'cltbld', 05:37:04 INFO - 'MAIL': '/var/mail/cltbld', 05:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:04 INFO - 'MOZ_NO_REMOTE': '1', 05:37:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:04 INFO - 'NO_EM_RESTART': '1', 05:37:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:37:04 INFO - 'PWD': '/builds/slave/test-pgo', 05:37:04 INFO - 'SHELL': '/bin/bash', 05:37:04 INFO - 'SHLVL': '1', 05:37:04 INFO - 'TERM': 'linux', 05:37:04 INFO - 'TMOUT': '86400', 05:37:04 INFO - 'USER': 'cltbld', 05:37:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:04 INFO - '_': '/tools/buildbot/bin/python'} 05:37:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 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)) 05:37:05 INFO - Cleaning up... 05:37:05 INFO - Return code: 0 05:37:05 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:37:05 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')]} 05:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:05 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')]} 05:37:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:37:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:37:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:37:05 INFO - Using env: {'DISPLAY': ':0', 05:37:05 INFO - 'HOME': '/home/cltbld', 05:37:05 INFO - 'LANG': 'en_US.UTF-8', 05:37:05 INFO - 'LANGUAGE': 'en_US:en', 05:37:05 INFO - 'LOGNAME': 'cltbld', 05:37:05 INFO - 'MAIL': '/var/mail/cltbld', 05:37:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:05 INFO - 'MOZ_NO_REMOTE': '1', 05:37:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:05 INFO - 'NO_EM_RESTART': '1', 05:37:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:37:05 INFO - 'PWD': '/builds/slave/test-pgo', 05:37:05 INFO - 'SHELL': '/bin/bash', 05:37:05 INFO - 'SHLVL': '1', 05:37:05 INFO - 'TERM': 'linux', 05:37:05 INFO - 'TMOUT': '86400', 05:37:05 INFO - 'USER': 'cltbld', 05:37:05 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:37:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:05 INFO - '_': '/tools/buildbot/bin/python'} 05:37:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:05 INFO - Downloading/unpacking jsonschema 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:37:07 INFO - Downloading/unpacking functools32 (from jsonschema) 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37:07 INFO - Downloading functools32-3.2.3-2.tar.gz 05:37:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:37:07 INFO - warning: no files found matching '*.txt' 05:37:07 INFO - no previously-included directories found matching 'build' 05:37:07 INFO - no previously-included directories found matching 'dist' 05:37:07 INFO - no previously-included directories found matching '.git*' 05:37:07 INFO - Installing collected packages: jsonschema, functools32 05:37:07 INFO - Running setup.py install for functools32 05:37:07 INFO - warning: no files found matching '*.txt' 05:37:07 INFO - no previously-included directories found matching 'build' 05:37:07 INFO - no previously-included directories found matching 'dist' 05:37:07 INFO - no previously-included directories found matching '.git*' 05:37:07 INFO - Successfully installed jsonschema functools32 05:37:07 INFO - Cleaning up... 05:37:07 INFO - Return code: 0 05:37:07 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:37:07 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')]} 05:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:07 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')]} 05:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:37:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa086952f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22788a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2348630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, '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': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', '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 05:37:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 05:37:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:37:07 INFO - Using env: {'DISPLAY': ':0', 05:37:07 INFO - 'HOME': '/home/cltbld', 05:37:07 INFO - 'LANG': 'en_US.UTF-8', 05:37:07 INFO - 'LANGUAGE': 'en_US:en', 05:37:07 INFO - 'LOGNAME': 'cltbld', 05:37:07 INFO - 'MAIL': '/var/mail/cltbld', 05:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:07 INFO - 'MOZ_NO_REMOTE': '1', 05:37:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:07 INFO - 'NO_EM_RESTART': '1', 05:37:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:37:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:37:07 INFO - 'SHELL': '/bin/bash', 05:37:07 INFO - 'SHLVL': '1', 05:37:07 INFO - 'TERM': 'linux', 05:37:07 INFO - 'TMOUT': '86400', 05:37:07 INFO - 'USER': 'cltbld', 05:37:07 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:07 INFO - '_': '/tools/buildbot/bin/python'} 05:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:07 INFO - Downloading/unpacking flake8 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37: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 05:37:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:37:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:37:09 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 05:37:09 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 05:37:09 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 05:37:09 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 05:37:09 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 05:37:09 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 05:37:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:37:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:37:09 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 05:37:09 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 05:37:09 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 05:37:09 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 05:37:09 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 05:37:09 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 05:37:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:37:10 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 05:37:10 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 05:37:10 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 05:37:10 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 05:37:10 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 05:37:10 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 05:37:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:37:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:37:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:37:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:37:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:37:10 INFO - no previously-included directories found matching 'docs/_build' 05:37:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:37:10 INFO - Running setup.py install for pep8 05:37:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:37:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:37:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:37:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:37:10 INFO - no previously-included directories found matching 'docs/_build' 05:37:10 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:37:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:37:10 INFO - Cleaning up... 05:37:10 INFO - Return code: 0 05:37:10 INFO - Running post-action listener: _resource_record_post_action 05:37:10 INFO - Running post-action listener: _start_resource_monitoring 05:37:10 INFO - Starting resource monitoring. 05:37:10 INFO - ##### 05:37:10 INFO - ##### Running install step. 05:37:10 INFO - ##### 05:37:10 INFO - Running pre-action listener: _resource_record_pre_action 05:37:10 INFO - Running main action method: install 05:37:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:37:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:37:10 INFO - Reading from file tmpfile_stdout 05:37:10 INFO - Detecting whether we're running mozinstall >=1.0... 05:37:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:37:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:37:10 INFO - Reading from file tmpfile_stdout 05:37:10 INFO - Output received: 05:37:10 INFO - Usage: mozinstall [options] installer 05:37:10 INFO - Options: 05:37:10 INFO - -h, --help show this help message and exit 05:37:10 INFO - -d DEST, --destination=DEST 05:37:10 INFO - Directory to install application into. [default: 05:37:10 INFO - "/builds/slave/test-pgo"] 05:37:10 INFO - --app=APP Application being installed. [default: firefox] 05:37:10 INFO - mkdir: /builds/slave/test-pgo/build/application 05:37:10 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'] 05:37:10 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 05:37:25 INFO - Reading from file tmpfile_stdout 05:37:25 INFO - Output received: 05:37:25 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:37:25 INFO - Running post-action listener: _resource_record_post_action 05:37:25 INFO - ##### 05:37:25 INFO - ##### Running run-tests step. 05:37:25 INFO - ##### 05:37:25 INFO - Running pre-action listener: _resource_record_pre_action 05:37:25 INFO - Running main action method: run_tests 05:37:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:37:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:37:25 INFO - Python 2.7.3 05:37:25 INFO - Return code: 0 05:37:25 INFO - grabbing minidump binary from tooltool 05:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:37:25 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 0x23d9840>, '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 0x23466b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2346b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:37:25 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 05:37:25 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 05:37:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:37:25 INFO - Return code: 0 05:37:25 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:37:25 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:37:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:37:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:37:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:37:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:37:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:37:26 INFO - Return code: 0 05:37:26 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/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 05:37:26 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/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --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 05:37:26 INFO - Using env: {'DISPLAY': ':0', 05:37:26 INFO - 'HOME': '/home/cltbld', 05:37:26 INFO - 'LANG': 'en_US.UTF-8', 05:37:26 INFO - 'LANGUAGE': 'en_US:en', 05:37:26 INFO - 'LOGNAME': 'cltbld', 05:37:26 INFO - 'MAIL': '/var/mail/cltbld', 05:37:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:37:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:26 INFO - 'MOZ_NO_REMOTE': '1', 05:37:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:37:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:26 INFO - 'NO_EM_RESTART': '1', 05:37:26 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:37:26 INFO - 'PWD': '/builds/slave/test-pgo', 05:37:26 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:37:26 INFO - 'SHELL': '/bin/bash', 05:37:26 INFO - 'SHLVL': '1', 05:37:26 INFO - 'TERM': 'linux', 05:37:26 INFO - 'TMOUT': '86400', 05:37:26 INFO - 'USER': 'cltbld', 05:37:26 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483', 05:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:26 INFO - '_': '/tools/buildbot/bin/python'} 05:37:26 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/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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 05:37:26 INFO - mozversion application_buildid: 20160121030208 05:37:26 INFO - mozversion application_changeset: 977d78a8dd78afbc0153d37fd9887c3a200dce6a 05:37:26 INFO - mozversion application_display_name: Nightly 05:37:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:37:26 INFO - mozversion application_name: Firefox 05:37:26 INFO - mozversion application_remotingname: firefox 05:37:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:37:26 INFO - mozversion application_vendor: Mozilla 05:37:26 INFO - mozversion application_version: 46.0a1 05:37:26 INFO - mozversion platform_buildid: 20160121030208 05:37:26 INFO - mozversion platform_changeset: 977d78a8dd78afbc0153d37fd9887c3a200dce6a 05:37:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:37:26 INFO - mozversion platform_version: 46.0a1 05:37:26 INFO - using testdate: 1453383446 05:37:26 INFO - actual date: 1453383446 05:37:26 INFO - starting webserver on 'localhost:48609' 05:37:26 INFO - SUITE-START | Running 3 tests 05:37:26 INFO - TEST-START | dromaeo_css 05:37:26 INFO - operating with platform_type : linux_ 05:37:26 INFO - Initialising browser for dromaeo_css test... 05:37:26 INFO - TEST-INFO | started process 18546 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplJ0nSS/profile http://localhost:48609/getInfo.html) 05:37:31 INFO - PROCESS | 18546 | __metrics Screen width/height:1600/1200 05:37:31 INFO - PROCESS | 18546 | colorDepth:24 05:37:31 INFO - PROCESS | 18546 | Browser inner width/height: 1024/697 05:37:31 INFO - PROCESS | 18546 | __metrics 05:37:31 INFO - PROCESS | 18546 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:37:31 INFO - PROCESS | 18546 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:37:32 INFO - TEST-INFO | 18546: exit 0 05:37:32 INFO - Browser initialized. 05:37:32 INFO - Running cycle 1/1 for dromaeo_css test... 05:37:32 INFO - TEST-INFO | started process 18638 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplJ0nSS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:37:44 INFO - PROCESS | 18638 | RSS: Main: 149307392 05:37:44 INFO - PROCESS | 18638 | 05:38:34 INFO - PROCESS | 18638 | 1453383514823 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] 05:40:44 INFO - PROCESS | 18638 | Cycle 1(1): loaded http://localhost:48609/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48609/tests/dromaeo/cssquery-ext.html) 05:40:45 INFO - PROCESS | 18638 | RSS: Main: 228003840 05:40:45 INFO - PROCESS | 18638 | 05:43:47 INFO - PROCESS | 18638 | Cycle 1(1): loaded http://localhost:48609/tests/dromaeo/cssquery-ext.html (next: http://localhost:48609/tests/dromaeo/cssquery-jquery.html) 05:43:48 INFO - PROCESS | 18638 | RSS: Main: 228089856 05:43:48 INFO - PROCESS | 18638 | 05:46:50 INFO - PROCESS | 18638 | Cycle 1(1): loaded http://localhost:48609/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48609/tests/dromaeo/cssquery-mootools.html) 05:46:51 INFO - PROCESS | 18638 | RSS: Main: 248168448 05:46:51 INFO - PROCESS | 18638 | 05:49:52 INFO - PROCESS | 18638 | Cycle 1(1): loaded http://localhost:48609/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48609/tests/dromaeo/cssquery-prototype.html) 05:49:53 INFO - PROCESS | 18638 | RSS: Main: 208224256 05:49:53 INFO - PROCESS | 18638 | 05:52:56 INFO - PROCESS | 18638 | Cycle 1(1): loaded http://localhost:48609/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48609/tests/dromaeo/cssquery-yui.html) 05:52:56 INFO - PROCESS | 18638 | RSS: Main: 212758528 05:52:56 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | Cycle 1(1): loaded http://localhost:48609/tests/dromaeo/cssquery-yui.html (next: http://localhost:48609/tests/dromaeo/cssquery-dojo.html) 05:55:59 INFO - PROCESS | 18638 | RSS: Main: 283451392 05:55:59 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | __start_tp_report 05:55:59 INFO - PROCESS | 18638 | _x_x_mozilla_page_load 05:55:59 INFO - PROCESS | 18638 | _x_x_mozilla_page_load_details 05:55:59 INFO - PROCESS | 18638 | |i|pagename|runs| 05:55:59 INFO - PROCESS | 18638 | |0;dojo.html;3183.140552050946;3201.6798320167973;3245.7403407727384;3248.658890816464;3269.6076470823496;5158.174692049273;5243.947560524393;5257.579393648509;5258.079836028694;5338.306020217376;5269.552088072518;5303.204519322104;5316.069687804638;5337.439568845264;5361.812336568221;4748.382710247672;4801.279808028806;4805.327254184417;4857.125717370872;4913.066517361708;4809.90380192396;4848.612111031127;4890.242012488052;4891.926621100686;4896.996115796253;4733.408323959505;4737.73942433166;4849.199882019462;4856.951430485703;4861.78121984508;4862.222044472903;4895.583875370562;4917.114919314522;4941.160002799496;4957.950420495821;4750.216214324632;4871.902561948777;4886.462489126229;4907.067657145131;4948.678335908155;5055.949440505621;5092.516210959953;5104.285400043997;5134.614903882187;5137.691738495665;5641.435856414326;5677.836043611092;5681;5693.316801983747;5701.543876489871;5624.859378515529;5672.12082127271;5704.258446401983;5709.971450142764;5738.770449182028;5329.813456529042;5345.118055520835;5366.704831234241;5367.677939323653;5387.407385187587;5840.386759390288;5845.181674565564;5850.5612079094235;5854.473097421167;5896.233489646319;5524.392316845144;5592.049351610236;5631.352394474606;5634;5638.577106717013;5612.579056570774;5670.886582268332;5701.800436984686;5713.143028545752;5755.395683453261;5604.859878502988;5647.858803529863;5659.5755318351285;5669.06460434032;5672.546196304368;5526.364468086141;5615.101583746581;5675.545956323567;5682.829515114553;5694.202811606378;4364.803583838735;4415.094905544356;4417.801198946056;4439.311906654474;4483.439570053743;3919.5884432134785;3940.2119576084897;3963.2667956428154;3976.701747368959;3980.7810570418324;5204.76578553966;5279.41926388097;5280.234366016906;5290.285811415487;5324.334458192726;5618.269624948731;5710.143478478261;5758.222639943638;5762.193292938992;5811.215485909433;5214.270002199695;5288.68267903927;5290.867728306823;5301.708406037628;5305.151175811929;4833.613310935117;4882.121218180761;4882.414110306786;4909.484504127086;4915.606751459874;5806.883862322815;5860.208871802422;5873.530117590582;5878.353381128072;5928.940710592925;5641.915371269516;5688.345840228261;5691.77232910679;5697.3448053474385;5707.857303567444;5756.71216439168;5881.353051164283;5890.440408161217;5897.64614123154;5911.467967882912;5606.579506537108;5657.066584013592;5668.489835914623;5669;5689.77240910359;5677.858053548694;5704.372519022987;5726.055200891807;5734.2258795062135;5737.48362647347;5466.753996070107;5550.333959924835;5561.138023606348;5596.776128954796;5639.2668953035845;5499.615026948075;5505.9174112387545;5518.2826232589505;5524.972375138099;5542.140968149944;5432.266644003167;5438.075527160313;5438.293021907127;5442;5495.313085864267;5442.891142177214;5474.945250547444;5522.97238513821;5523.088690366045;5544.974179776754;5439.782408703766;5486.972565137149;5488;5499.835004949858;5511.118221084607 05:55:59 INFO - PROCESS | 18638 | |1;ext.html;533.3759501383381;556.81903381401;586.7095787585146;592.256717819137;605.1346574398609;28858.711412885867;29266.561001584967;29278.2680432989;29293;29294.707052929465;29415.26461838455;35995.2800943981;36586.817065914634;36640.816795915984;36662.816685916536;8329.83340333193;8467.364947628921;8483.74548763538;8490.235878770909;8518.87221691675;8523.57382130891;8698.086700896378;8707.129287071273;8714.25928796053;8714.651413943466;8658.653653853838;8743.781405464852;8753.168448997336;8754.956225218864;8760.780980475507;8409.95795021024;8458.74623761288;8459.238668519863;8463.873041904377;8470.66117355308;8393.790155246106;8551.914480855174;8560.61477233526;8572.485650860968;8573.52845593492;38108.23783524342;38698.80650596757;39107.217855642724;39120.21759564821;39130;3123.7657175711684;3322.6178989416044;3733.4959780429567;3742;3824.9426258606145;28896.254855788666;29095.41809163826;29112;30497;30707.385852283052;3544.113971507123;3551.4317709166407;3566.7681600696133;3663.5603727552866;3725.217704282104;253630;254024.99999999814;254418;255696;256530;48857.511424886005;49093.50906490961;50477.24284135741;51013.23480147801;57944.654150938004;51456.22815657768;51544.74227628838;52123.739381302854;52362.476375236525;52415.475845241825;14942;15059.548213553611;15103.54689359321;15348.309326080353;15348.309326080353;10626.043656070837;10751.51618177184;11100.833487497694;11164.888351116548;11228.943855280671;18183.272669093352;18216.544586385182;18294.54263643393;18337.266509339475;18404.907975460035;7082.220955694869;7152.569486102696;7157.89263161053;7271.527350722292;7311.159216690149;1027.3989716016065;1064.9041586257274;1136.7044568412273;1143.6511863881597;1157.9826302605468;28471.28821779472;28505.857470712515;29601.111966641034;30665.233369165504;31343.37313253722;1015.1016350529834;1084.1489430796787;1084.2356138922041;1087.6573879228017;1113.5434471866495;14654.19401932904;14661.85338146605;14869;15114.697706045818;15148.242587870562;283673;290401;291299;291350;292325;53942.46057539453;54300.45699542954;54739;54854.17718734222;54964;1388.9097208681605;1398.8111604933408;1403.031907983488;1407.5777266819914;1410.2737090398452;25872.353191170372;28340.57489137706;29205;29745.405091897606;30829;49386.259206111215;50168.74915625399;50222.7488862568;50279.49720502748;50415.49584504255;241231;243441;244655;245367;245581;28257.57613635756;28885.56671649969;32241.193970149994;32369;32830.34339313152;37029.44455833219;37410;37515;37633.623663764105;37879.62120378761;30313.696863031088;31319.874848043495;31998.36717626067;34162.82918585391;34522;34233.82883085569;34878.302433950674;34925.65074349224;35061.82469087638;35100.64899350974;34662.82668586641;34717.82641086778;37824;38312.42531361976;38932.221355573296;30807.69192308048;32837.83581082079;33091.66908330982;33123;33140.668593313756;24608.876955615106;24975.625365619166;25397.619035714848;25513.74486255188;27128 05:55:59 INFO - PROCESS | 18638 | |2;jquery.html;371.05381277741753;374.730194260133;374.8631749411464;375.79894756305373;378.4550247643393;13827.861721382784;14339.21134337611;15319.770203446957;17042.403515876947;17511.474655760347;11072.114230861533;11288.379139147344;11318.32090074596;11319.434028298594;17089;4364.25807612705;5908.1728558001805;6110.388961103877;6112.938870611304;6120.4797592204495;4495.505494395614;6536.215654121512;6758.763443279487;6767.796966091;6769.7630582929605;6497.350264973488;6803.013563033382;6811.557248778813;6811.761588344409;6821.761238356658;6591.934080659204;6697;6720.899186512206;6919.100516932817;6931.584104953668;6326.304106548276;6552.901706474407;6599.3070727573395;6619.966900165517;6621.834454138637;21941;22003.779962200333;22412.43968900774;22878.31365059051;22904.312870613907;2313.8727369994645;2324.7442781294044;2447.7919376852988;2558.475512519929;2805.859707014641;13475.460981560725;13813.378397972116;13870.930645346809;14110.788338174936;14400.423983040666;1794.7128459446426;1918;1918.3015265287531;1937.7674679038605;2000.339887837025;61824.69087654578;63139;64478.35521644771;64584.67707661479;65094;14587.926822001064;15052;15089.396424143131;15095.924520377328;15502.5349239523;23494.88252558726;23613.173538926054;23855;23950.64074038891;24007.159749409035;6746.4265537429865;7065.2228254891925;7129.500934934505;7244.311790379906;8664.523451210118;6719.899201511981;6770.796876093725;6784.728610855511;6801.285864984205;6810.387065164159;4245.724027938236;4337.631301339421;4403.471583410012;4504.436945381827;4523.796429160696;1839.8620103492024;1847.3811273223353;1873.4286042756817;1877.080230686981;1877.0896115384028;808.9726047919228;896.5651658945407;947.2422062350092;1011.7521207304111;1045.242199405425;14699.412023519153;15287.312070956834;16028.839711602735;16161.838381616033;16573.585660358345;957.913787759105;959.7696552827272;972.4214092614883;979.0160888307206;1016.6441745389203;9108.089191080839;9368.67209647659;9380.343375963617;9515.33392662507;9767.51162441875;528585;529317;534372;545938;548881;52877;55792;70945.64527177434;71882.2811771886;72920.63539682268;1014.3305418423805;1045.383223897904;1046.4924511611862;1108.6507750058706;1158.438157493598;14411.711765764836;14691.11853288806;14727.190004549853;15282.923585382447;15327.157006364758;30218.84890575533;33233.83383083069;33613;34368.65631343654;34485.655143448246;34767.82616086904;35757.64242357543;36056.4591531122;36092;38207.426888596136;4659.394278743742;4906.9018619626695;7500.737474188377;7557.2820582044615;7627.580483073484;9895.257855660999;10736.570537178737;11125.554977800797;11186.32882027081;11548.480318385526;8896.688615898413;9145.954270228607;9323.114304141096;9434.905650943678;9460;9642.42145471274;9653.324267301221;9697.127258546483;10022.498875056364;10207.183425326104;7415.555066696015;9265.490398028172;9467.431954082776;9583.185429238592;9583.760405989906;7528.171901090986;7609.429292803173;7638.388928885564;7668.961655191688;7669.769906902578;8577.742667719978;9259.444433334022;9387.249020078241;9497.572609232739;9551.522423878918 05:55:59 INFO - PROCESS | 18638 | |3;mootools.html;1360.129517109051;1464.619199008258;1476.9483068092616;1621.5946013496625;1815.5824160443099;6990.440764738822;7704.306612404883;7844.764657060283;7845.09781375142;7865.764027079183;7696.307332340089;7772.96113519433;7854.332381747558;7860.68557257709;7862.606869656523;2057.763357213917;2092.884891330976;2350.8589484630893;2383.1182462488905;2416.758324167578;2394.1141777542334;2394.5330660521204;2398.8920498577604;2400.663907053014;2401.6397540369;2330.93007209784;2390.784829365357;2396.760323967607;2397.8081753459683;2399.8320117591775;2338.7895089441945;2364.798992085675;2388.3551441110803;2392.437777122375;2394.4133687246563;2353.446939969106;2355.281639100074;2382.988085059581;2389.689340385756;2402.3633737059695;7813;7845;7857.489263197931;7858.8428231435055;7873.094594121692;4904.166291730415;5013.749312534395;5044.39467263927;5053.418856831475;5062.417821950486;3289.210589458513;3294.1435226841195;3363.3441478911623;3391.5930088389305;3406.9659303407143;1045.8692663417073;1068.1881769854888;1089.4824958144898;1092.5684354679936;1102.7132945434164;11156.776864462747;11199.496022678999;11200.383978881158;11205.775884482346;11222.775544489064;4582.198115329804;4595.448546174481;4655.11552804966;4660.370849935283;4661.044485880387;9456.8581471278;9483.430994140374;9492.050794920453;9511.952440237754;9512.762180945394;5036.823711170092;5043.773030213649;5047.268146118792;5069.923951140736;5076.796928122833;4615.100055489214;4621.745803980813;4629.490756016836;4631.8378856739855;4633.559811817872;3522.330757156132;3614.0964758810296;3790.2608991246443;3824.388097904322;3863.517060367454;1267.2206592945272;1269.8730126987227;1277.974440511203;1279.4945996331583;1288.7293668329569;1052.1057101463848;1078.978420431587;1080.400377790318;1080.6974047266779;1146.4611632532697;7512.812179695551;7551.207123252089;7599.810004749815;7640.541567505968;7654.158042615308;1119.9664010079712;1127.1490025031212;1136.9943150284362;1139.2082502660571;1154.2151337090859;5605.411431799684;5668.489835914787;5677.233573467612;5685.317761868602;5700.914486282709;9653.22774178064;9719;9788.314817962817;9871.259655525864;9899.30704850668;9719.951400243095;9726.75683107928;9732.172765315028;9758.072983066597;9786.902130978742;7355.558666479921;7570.772876813704;7607.049118860143;7614.657340419694;7639.350655194371;7257.237990010973;7294.708211671474;7301.08736407949;7302.123745150616;7355.411567074514;8510.106438823834;8590.484570925764;8783.165599268059;8805.559722013746;8933.19601235892;8804.779880503038;8931.553422328987;8944.910550894409;9101.317401195069;9101.499417531835;6337.7781777637565;7082.752103676346;7109.182444019004;7165.211826698955;7181.569105853666;6967.268436814062;7351.228121047427;7555.09338879338;7559.168491465824;7573.886391704239;7001.894971575329;7096.964515177391;7162.033125527987;7171.677274522554;7281.708731650675;7162.820929476805;7193.028941092885;7213.891791623025;7220.819479512844;7327.816804579928;6947.444204463732;6989.580625162506;7002.649867506706;7151.964240178766;7157.320054594805;6603.108580341785;6646.9335306648245;6650.135482387259;6657.933420665925;6660.134182556237;6616.371444712745;6659.167604049494;6670.733170673118;6711.228208756055;6713.1944166700305 05:55:59 INFO - PROCESS | 18638 | |4;prototype.html;334.9363620912027;345.9948100778488;359.12732061091555;359.8610398090045;365.0654324928184;5697.202391665171;5869.442402971717;5870.589058765888;5882.529397648189;5906.911396329058;5636.351819540754;5778.162166485868;5808.3320418151925;5835.737391817368;5838.124281357798;4565.977170114144;4569.223232050559;4578.633709303264;4580.541945805409;4582.335561343603;4394.121175764843;4474.619657329131;4494.820207191725;4561.7262964222255;4595.862124136264;4531.592156705896;4545.545445455445;4546.204414227497;4554.521775213605;4564.42944631921;4529.682922195448;4547.567981041812;4556.726596404226;4556.817727290904;4563.361129441881;4045.2516284487465;4050.7164498485117;4052;4054.7161698681102;4061.614146656063;5439.864003399907;5576.163575463673;5576.637518561281;5581.079121944874;5607.130894711286;5215.608829337815;5225.294585230983;5227.581793456515;5230.163173892159;5231.189165679326;5915.142304365886;6238.9376106238815;6239.532035097341;6243.8126856194385;6244.094606282109;4288.142371525707;4288.656907447428;4386.385905973167;4394.824207031715;4404.603585677272;6402.583832050902;6452.64510451925;6455.644939528371;6463.224413070415;6485.286618471964;4474.574915383034;4489.9551004490195;4490.640748740093;4495.865124046284;4501.752403617833;4443;4558.954410455919;4563.475200351935;4568.246239370533;4579.198640237945;5224.425313215544;5232.037985755342;5238.71187084714;5239;5246.108161612535;6237.719302631393;6239.376062393724;6250.4374606285655;6302.4958003359625;6318.62088274705;1568.5764843492193;1605.0289574807073;1607.5338151936041;1610.2995197089303;1611.0575313441534;1077.1006209814736;1080.897314755097;1092.049916572571;1099.780043991205;1115.2026301194685;5917.7928772493615;5930.703464826742;5931.051031834972;5934.495567876779;5938.435848594377;6406.071119687619;6407.519436042316;6422.261439934374;6432.2281326241155;6433.388828061326;5906.497947674496;5913.615614985012;5922.289325280994;5930.258717660293;5934.6142500737315;3372;3462.065242384542;3465.2203254268093;3470.236547959445;3472.166679996833;6637.601743895381;6656.667166641649;6662.333766623396;6669.1330127083165;6681.33186681328;6525.412712855866;6584.176977877765;6589.978553324242;6592.571482853599;6604.471642268607;6482.513811464159;6631.204255489372;6637.601743895381;6638.734450622114;6638.805015097329;6190.938090619036;6339.778107766206;6437.195350581177;6447.323031081669;6462.773802916875;6216.502679785516;6339.96830015847;6372.904406434;6374.362563743589;6381.1704478417505;6578.111954886029;6634.800955971328;6635.900461493178;6637.103990961352;6640.203175618763;4441.133978874218;4478.843240486567;4521.61566266871;4524.298733696282;4525.570070843265;6368.840778980562;6389.073584330339;6393.041043843461;6398.808035758934;6399.8080057598345;6247.843803904939;6256.249250090018;6258.718357673824;6259.749610015549;6269.529785266214;6184.072389141665;6251.093591429127;6302.936970630235;6333.493320534254;6337.04944258365;6117.96941015292;6142.57002009855;6145.815625531062;6153.769246150699;6154.292256390572;6226.25284965807;6285.905711414241;6286.5599408041;6294.496440284858;6313.747450101944;5987.221661184019;6127.172831667668;6145.569810113249;6150.446459818638;6153.13856060143 05:55:59 INFO - PROCESS | 18638 | |5;yui.html;736.6721808795087;743.6950850151437;762.713982256654;774.8605251054807;786.830831371255;13231.139975901571;13550.728985420297;13580.117292375988;15419.15194664293;15773.60565985851;14168.291585420739;14178.511256318066;14213.431462741504;14318.856811431882;14342.71314573708;675.797260821754;683.9555428897131;815.3477218225411;830.4353039932844;830.9127541608104;807.1726480357622;821.2690705272313;828.5194587139445;829.8049958259804;830.0495932157676;858.6393714639867;858.9742307730747;860.096898256831;863.7365603490932;865.0873328638285;697.9825504362381;702.9841878485565;852.7569642651846;857.5155037403847;860.8622620380709;630.3224034163293;630.6941133550224;631.2235949781748;631.9210098737658;633.4678869749387;13473.326333683375;13495.932520337336;13561.321933903266;13564.45742170312;13590.38843252056;12229.205101668453;12421.31682757448;12493.625191244277;12521.186122901981;12930.030247731363;10459;10735.83896241557;10763;10877.12982961369;11008.119350451945;3600.4959305697225;3679.5400574928135;3695.260947810422;3777.395616701315;3782.9432558511644;43179.13641727148;43514.782426087666;43522.56477435217;43548.564514354766;43550.782246088565;9803.532052682822;10123.696289111338;10133.18934485254;10141.847872281922;10339.483025848678;17461.38885139014;17498.91250543739;17587.648247034987;17612.471625851245;17656.558586035197;7188.568685878759;7298.96350518244;7396.482246242819;7490.250974902466;7582.469227154156;4162.334026555736;4177.811998460076;4187.392828039978;4217.936730949038;4312.439382880206;3228.580284562992;3272.9018129456153;3275.3449310138067;3276.0499455158215;3282.967170328314;614.757170917485;614.8647297594522;620.4447019917149;634.3624657219465;651.8077167235676;532.2495281652851;548.9934290169438;603.2670305578743;637.7863415755774;707.3669066185735;8175.672973081129;8402.663893444316;8542.359323050796;8586.22723954847;8623.396362254582;584.7865529081939;585.4233579923772;638.8981494842566;712.9484011083714;713.1014921199326;4022.8591999279884;4102.446169767103;4169.97915010423;4180.937285940713;4242.490901091889;51405;52032.73983630058;52892;53337;53532;45755;46473.535264647595;46951.29573056407;46993;47304.7634761824;39559.80220098938;40747.18505629871;41482.22183353763;41635.75463680472;42216.57783422188;8033.437659364022;8101.797455063671;8244.422890397615;8263.913351955547;8317.916820831715;31225.219369515944;31494.685053149176;31523.527147092354;32304.35391292208;32651;37829.621703782606;38232;38321.4251786229;41796.79101604473;42392;10196.643117490852;10254.333468324536;10259.94870025675;10365.63720269787;10924.235303528676;11235.775284494428;11346.716332091764;11398.715032124263;11577.247478913843;11635.301881887113;10584.629537966135;10631.893681063091;10749.247552671239;10830.566777328819;11279.548818047186;10103.797924041331;10138.543765530716;10353;10390.480475976323;10443.373397596168;8261.413663989464;10143.797124057624;10421.53103110377;11139.554417823521;11544.826827597424;9399.812003760024;9548.61805527799;9711.562979666074;9756.51217439111;9945.900540994788;9328.626854925727;9794.608215671293;9810.215182785218;9880.258980576342;9960.501974901372 05:55:59 INFO - PROCESS | 18638 | __end_tp_report 05:55:59 INFO - PROCESS | 18638 | __start_cc_report 05:55:59 INFO - PROCESS | 18638 | _x_x_mozilla_cycle_collect,889 05:55:59 INFO - PROCESS | 18638 | __end_cc_report 05:55:59 INFO - PROCESS | 18638 | __startTimestamp1453384559522__endTimestamp 05:55:59 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | ------- Summary: start ------- 05:55:59 INFO - PROCESS | 18638 | Number of tests: 6 05:55:59 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | [#0] dojo.html Cycles:165 Average:5279.65 Median:5474.95 stddev:570.85 (10.4%) stddev-sans-first:548.39 05:55:59 INFO - PROCESS | 18638 | Values: 3183.1 3201.7 3245.7 3248.7 3269.6 5158.2 5243.9 5257.6 5258.1 5338.3 5269.6 5303.2 5316.1 5337.4 5361.8 4748.4 4801.3 4805.3 4857.1 4913.1 4809.9 4848.6 4890.2 4891.9 4897.0 4733.4 4737.7 4849.2 4857.0 4861.8 4862.2 4895.6 4917.1 4941.2 4958.0 4750.2 4871.9 4886.5 4907.1 4948.7 5055.9 5092.5 5104.3 5134.6 5137.7 5641.4 5677.8 5681.0 5693.3 5701.5 5624.9 5672.1 5704.3 5710.0 5738.8 5329.8 5345.1 5366.7 5367.7 5387.4 5840.4 5845.2 5850.6 5854.5 5896.2 5524.4 5592.0 5631.4 5634.0 5638.6 5612.6 5670.9 5701.8 5713.1 5755.4 5604.9 5647.9 5659.6 5669.1 5672.5 5526.4 5615.1 5675.5 5682.8 5694.2 4364.8 4415.1 4417.8 4439.3 4483.4 3919.6 3940.2 3963.3 3976.7 3980.8 5204.8 5279.4 5280.2 5290.3 5324.3 5618.3 5710.1 5758.2 5762.2 5811.2 5214.3 5288.7 5290.9 5301.7 5305.2 4833.6 4882.1 4882.4 4909.5 4915.6 5806.9 5860.2 5873.5 5878.4 5928.9 5641.9 5688.3 5691.8 5697.3 5707.9 5756.7 5881.4 5890.4 5897.6 5911.5 5606.6 5657.1 5668.5 5669.0 5689.8 5677.9 5704.4 5726.1 5734.2 5737.5 5466.8 5550.3 5561.1 5596.8 5639.3 5499.6 5505.9 5518.3 5525.0 5542.1 5432.3 5438.1 5438.3 5442.0 5495.3 5442.9 5474.9 5523.0 5523.1 5545.0 5439.8 5487.0 5488.0 5499.8 5511.1 05:55:59 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | [#1] ext.html Cycles:180 Average:42764.08 Median:18742.72 stddev:68666.56 (366.4%) stddev-sans-first:68785.98 05:55:59 INFO - PROCESS | 18638 | Values: 533.4 556.8 586.7 592.3 605.1 28858.7 29266.6 29278.3 29293.0 29294.7 29415.3 35995.3 36586.8 36640.8 36662.8 8329.8 8467.4 8483.7 8490.2 8518.9 8523.6 8698.1 8707.1 8714.3 8714.7 8658.7 8743.8 8753.2 8755.0 8760.8 8410.0 8458.7 8459.2 8463.9 8470.7 8393.8 8551.9 8560.6 8572.5 8573.5 38108.2 38698.8 39107.2 39120.2 39130.0 3123.8 3322.6 3733.5 3742.0 3824.9 28896.3 29095.4 29112.0 30497.0 30707.4 3544.1 3551.4 3566.8 3663.6 3725.2 253630.0 254025.0 254418.0 255696.0 256530.0 48857.5 49093.5 50477.2 51013.2 57944.7 51456.2 51544.7 52123.7 52362.5 52415.5 14942.0 15059.5 15103.5 15348.3 15348.3 10626.0 10751.5 11100.8 11164.9 11228.9 18183.3 18216.5 18294.5 18337.3 18404.9 7082.2 7152.6 7157.9 7271.5 7311.2 1027.4 1064.9 1136.7 1143.7 1158.0 28471.3 28505.9 29601.1 30665.2 31343.4 1015.1 1084.1 1084.2 1087.7 1113.5 14654.2 14661.9 14869.0 15114.7 15148.2 283673.0 290401.0 291299.0 291350.0 292325.0 53942.5 54300.5 54739.0 54854.2 54964.0 1388.9 1398.8 1403.0 1407.6 1410.3 25872.4 28340.6 29205.0 29745.4 30829.0 49386.3 50168.7 50222.7 50279.5 50415.5 241231.0 243441.0 244655.0 245367.0 245581.0 28257.6 28885.6 32241.2 32369.0 32830.3 37029.4 37410.0 37515.0 37633.6 37879.6 30313.7 31319.9 31998.4 34162.8 34522.0 34233.8 34878.3 34925.7 35061.8 35100.6 34662.8 34717.8 37824.0 38312.4 38932.2 30807.7 32837.8 33091.7 33123.0 33140.7 24608.9 24975.6 25397.6 25513.7 27128.0 05:55:59 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | [#2] jquery.html Cycles:180 Average:27817.13 Median:4591.60 stddev:87672.94 (1909.4%) stddev-sans-first:87894.67 05:55:59 INFO - PROCESS | 18638 | Values: 371.1 374.7 374.9 375.8 378.5 13827.9 14339.2 15319.8 17042.4 17511.5 11072.1 11288.4 11318.3 11319.4 17089.0 4364.3 5908.2 6110.4 6112.9 6120.5 4495.5 6536.2 6758.8 6767.8 6769.8 6497.4 6803.0 6811.6 6811.8 6821.8 6591.9 6697.0 6720.9 6919.1 6931.6 6326.3 6552.9 6599.3 6620.0 6621.8 21941.0 22003.8 22412.4 22878.3 22904.3 2313.9 2324.7 2447.8 2558.5 2805.9 13475.5 13813.4 13870.9 14110.8 14400.4 1794.7 1918.0 1918.3 1937.8 2000.3 61824.7 63139.0 64478.4 64584.7 65094.0 14587.9 15052.0 15089.4 15095.9 15502.5 23494.9 23613.2 23855.0 23950.6 24007.2 6746.4 7065.2 7129.5 7244.3 8664.5 6719.9 6770.8 6784.7 6801.3 6810.4 4245.7 4337.6 4403.5 4504.4 4523.8 1839.9 1847.4 1873.4 1877.1 1877.1 809.0 896.6 947.2 1011.8 1045.2 14699.4 15287.3 16028.8 16161.8 16573.6 957.9 959.8 972.4 979.0 1016.6 9108.1 9368.7 9380.3 9515.3 9767.5 528585.0 529317.0 534372.0 545938.0 548881.0 52877.0 55792.0 70945.6 71882.3 72920.6 1014.3 1045.4 1046.5 1108.7 1158.4 14411.7 14691.1 14727.2 15282.9 15327.2 30218.8 33233.8 33613.0 34368.7 34485.7 34767.8 35757.6 36056.5 36092.0 38207.4 4659.4 4906.9 7500.7 7557.3 7627.6 9895.3 10736.6 11125.6 11186.3 11548.5 8896.7 9146.0 9323.1 9434.9 9460.0 9642.4 9653.3 9697.1 10022.5 10207.2 7415.6 9265.5 9467.4 9583.2 9583.8 7528.2 7609.4 7638.4 7669.0 7669.8 8577.7 9259.4 9387.2 9497.6 9551.5 05:55:59 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | [#3] mootools.html Cycles:180 Average:5632.79 Median:6470.44 stddev:2902.36 (44.9%) stddev-sans-first:2892.72 05:55:59 INFO - PROCESS | 18638 | Values: 1360.1 1464.6 1476.9 1621.6 1815.6 6990.4 7704.3 7844.8 7845.1 7865.8 7696.3 7773.0 7854.3 7860.7 7862.6 2057.8 2092.9 2350.9 2383.1 2416.8 2394.1 2394.5 2398.9 2400.7 2401.6 2330.9 2390.8 2396.8 2397.8 2399.8 2338.8 2364.8 2388.4 2392.4 2394.4 2353.4 2355.3 2383.0 2389.7 2402.4 7813.0 7845.0 7857.5 7858.8 7873.1 4904.2 5013.7 5044.4 5053.4 5062.4 3289.2 3294.1 3363.3 3391.6 3407.0 1045.9 1068.2 1089.5 1092.6 1102.7 11156.8 11199.5 11200.4 11205.8 11222.8 4582.2 4595.4 4655.1 4660.4 4661.0 9456.9 9483.4 9492.1 9512.0 9512.8 5036.8 5043.8 5047.3 5069.9 5076.8 4615.1 4621.7 4629.5 4631.8 4633.6 3522.3 3614.1 3790.3 3824.4 3863.5 1267.2 1269.9 1278.0 1279.5 1288.7 1052.1 1079.0 1080.4 1080.7 1146.5 7512.8 7551.2 7599.8 7640.5 7654.2 1120.0 1127.1 1137.0 1139.2 1154.2 5605.4 5668.5 5677.2 5685.3 5700.9 9653.2 9719.0 9788.3 9871.3 9899.3 9720.0 9726.8 9732.2 9758.1 9786.9 7355.6 7570.8 7607.0 7614.7 7639.4 7257.2 7294.7 7301.1 7302.1 7355.4 8510.1 8590.5 8783.2 8805.6 8933.2 8804.8 8931.6 8944.9 9101.3 9101.5 6337.8 7082.8 7109.2 7165.2 7181.6 6967.3 7351.2 7555.1 7559.2 7573.9 7001.9 7097.0 7162.0 7171.7 7281.7 7162.8 7193.0 7213.9 7220.8 7327.8 6947.4 6989.6 7002.6 7152.0 7157.3 6603.1 6646.9 6650.1 6657.9 6660.1 6616.4 6659.2 6670.7 6711.2 6713.2 05:55:59 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | [#4] prototype.html Cycles:180 Average:5224.30 Median:5910.26 stddev:1550.18 (26.2%) stddev-sans-first:1510.47 05:55:59 INFO - PROCESS | 18638 | Values: 334.9 346.0 359.1 359.9 365.1 5697.2 5869.4 5870.6 5882.5 5906.9 5636.4 5778.2 5808.3 5835.7 5838.1 4566.0 4569.2 4578.6 4580.5 4582.3 4394.1 4474.6 4494.8 4561.7 4595.9 4531.6 4545.5 4546.2 4554.5 4564.4 4529.7 4547.6 4556.7 4556.8 4563.4 4045.3 4050.7 4052.0 4054.7 4061.6 5439.9 5576.2 5576.6 5581.1 5607.1 5215.6 5225.3 5227.6 5230.2 5231.2 5915.1 6238.9 6239.5 6243.8 6244.1 4288.1 4288.7 4386.4 4394.8 4404.6 6402.6 6452.6 6455.6 6463.2 6485.3 4474.6 4490.0 4490.6 4495.9 4501.8 4443.0 4559.0 4563.5 4568.2 4579.2 5224.4 5232.0 5238.7 5239.0 5246.1 6237.7 6239.4 6250.4 6302.5 6318.6 1568.6 1605.0 1607.5 1610.3 1611.1 1077.1 1080.9 1092.0 1099.8 1115.2 5917.8 5930.7 5931.1 5934.5 5938.4 6406.1 6407.5 6422.3 6432.2 6433.4 5906.5 5913.6 5922.3 5930.3 5934.6 3372.0 3462.1 3465.2 3470.2 3472.2 6637.6 6656.7 6662.3 6669.1 6681.3 6525.4 6584.2 6590.0 6592.6 6604.5 6482.5 6631.2 6637.6 6638.7 6638.8 6190.9 6339.8 6437.2 6447.3 6462.8 6216.5 6340.0 6372.9 6374.4 6381.2 6578.1 6634.8 6635.9 6637.1 6640.2 4441.1 4478.8 4521.6 4524.3 4525.6 6368.8 6389.1 6393.0 6398.8 6399.8 6247.8 6256.2 6258.7 6259.7 6269.5 6184.1 6251.1 6302.9 6333.5 6337.0 6118.0 6142.6 6145.8 6153.8 6154.3 6226.3 6285.9 6286.6 6294.5 6313.7 5987.2 6127.2 6145.6 6150.4 6153.1 05:55:59 INFO - PROCESS | 18638 | 05:55:59 INFO - PROCESS | 18638 | [#5] yui.html Cycles:180 Average:12975.02 Median:23317.25 stddev:14337.95 (61.5%) stddev-sans-first:14348.71 05:55:59 INFO - PROCESS | 18638 | Values: 736.7 743.7 762.7 774.9 786.8 13231.1 13550.7 13580.1 15419.2 15773.6 14168.3 14178.5 14213.4 14318.9 14342.7 675.8 684.0 815.3 830.4 830.9 807.2 821.3 828.5 829.8 830.0 858.6 859.0 860.1 863.7 865.1 698.0 703.0 852.8 857.5 860.9 630.3 630.7 631.2 631.9 633.5 13473.3 13495.9 13561.3 13564.5 13590.4 12229.2 12421.3 12493.6 12521.2 12930.0 10459.0 10735.8 10763.0 10877.1 11008.1 3600.5 3679.5 3695.3 3777.4 3782.9 43179.1 43514.8 43522.6 43548.6 43550.8 9803.5 10123.7 10133.2 10141.8 10339.5 17461.4 17498.9 17587.6 17612.5 17656.6 7188.6 7299.0 7396.5 7490.3 7582.5 4162.3 4177.8 4187.4 4217.9 4312.4 3228.6 3272.9 3275.3 3276.0 3283.0 614.8 614.9 620.4 634.4 651.8 532.2 549.0 603.3 637.8 707.4 8175.7 8402.7 8542.4 8586.2 8623.4 584.8 585.4 638.9 712.9 713.1 4022.9 4102.4 4170.0 4180.9 4242.5 51405.0 52032.7 52892.0 53337.0 53532.0 45755.0 46473.5 46951.3 46993.0 47304.8 39559.8 40747.2 41482.2 41635.8 42216.6 8033.4 8101.8 8244.4 8263.9 8317.9 31225.2 31494.7 31523.5 32304.4 32651.0 37829.6 38232.0 38321.4 41796.8 42392.0 10196.6 10254.3 10259.9 10365.6 10924.2 11235.8 11346.7 11398.7 11577.2 11635.3 10584.6 10631.9 10749.2 10830.6 11279.5 10103.8 10138.5 10353.0 10390.5 10443.4 8261.4 10143.8 10421.5 11139.6 11544.8 9399.8 9548.6 9711.6 9756.5 9945.9 9328.6 9794.6 9810.2 9880.3 9960.5 05:55:59 INFO - PROCESS | 18638 | -------- Summary: end -------- 05:55:59 INFO - PROCESS | 18638 | 05:56:00 INFO - TEST-INFO | 18638: exit 0 05:56:00 INFO - TEST-OK | dromaeo_css | took 1113538ms 05:56:00 INFO - TEST-START | kraken 05:56:00 INFO - operating with platform_type : linux_ 05:56:00 INFO - Initialising browser for kraken test... 05:56:00 INFO - TEST-INFO | started process 18837 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpe6Hj_p/profile http://localhost:48609/getInfo.html) 05:56:04 INFO - PROCESS | 18837 | __metrics Screen width/height:1600/1200 05:56:04 INFO - PROCESS | 18837 | colorDepth:24 05:56:04 INFO - PROCESS | 18837 | Browser inner width/height: 1024/697 05:56:04 INFO - PROCESS | 18837 | __metrics 05:56:04 INFO - PROCESS | 18837 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:56:04 INFO - PROCESS | 18837 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:56:05 INFO - TEST-INFO | 18837: exit 0 05:56:05 INFO - Browser initialized. 05:56:05 INFO - Running cycle 1/1 for kraken test... 05:56:05 INFO - TEST-INFO | started process 18917 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpe6Hj_p/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:56:17 INFO - PROCESS | 18917 | RSS: Main: 149389312 05:56:17 INFO - PROCESS | 18917 | 05:56:19 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48609/tests/kraken/driver.html?testName=audio-beat-detection) 05:56:20 INFO - PROCESS | 18917 | RSS: Main: 185352192 05:56:20 INFO - PROCESS | 18917 | 05:56:23 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48609/tests/kraken/driver.html?testName=audio-dft) 05:56:23 INFO - PROCESS | 18917 | RSS: Main: 176173056 05:56:23 INFO - PROCESS | 18917 | 05:56:26 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48609/tests/kraken/driver.html?testName=audio-fft) 05:56:27 INFO - PROCESS | 18917 | RSS: Main: 162455552 05:56:27 INFO - PROCESS | 18917 | 05:56:29 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48609/tests/kraken/driver.html?testName=audio-oscillator) 05:56:29 INFO - PROCESS | 18917 | RSS: Main: 164995072 05:56:29 INFO - PROCESS | 18917 | 05:56:31 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48609/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:56:32 INFO - PROCESS | 18917 | RSS: Main: 165060608 05:56:32 INFO - PROCESS | 18917 | 05:56:35 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48609/tests/kraken/driver.html?testName=imaging-darkroom) 05:56:36 INFO - PROCESS | 18917 | RSS: Main: 165076992 05:56:36 INFO - PROCESS | 18917 | 05:56:39 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48609/tests/kraken/driver.html?testName=imaging-desaturate) 05:56:40 INFO - PROCESS | 18917 | RSS: Main: 165228544 05:56:40 INFO - PROCESS | 18917 | 05:56:42 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48609/tests/kraken/driver.html?testName=json-parse-financial) 05:56:43 INFO - PROCESS | 18917 | RSS: Main: 164995072 05:56:43 INFO - PROCESS | 18917 | 05:56:44 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48609/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:56:45 INFO - PROCESS | 18917 | RSS: Main: 177373184 05:56:45 INFO - PROCESS | 18917 | 05:56:47 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48609/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:56:47 INFO - PROCESS | 18917 | RSS: Main: 174104576 05:56:47 INFO - PROCESS | 18917 | 05:56:49 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48609/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:56:50 INFO - PROCESS | 18917 | RSS: Main: 187023360 05:56:50 INFO - PROCESS | 18917 | 05:56:52 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48609/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:56:53 INFO - PROCESS | 18917 | RSS: Main: 195072000 05:56:53 INFO - PROCESS | 18917 | 05:56:55 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48609/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:56:56 INFO - PROCESS | 18917 | RSS: Main: 193462272 05:56:56 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | Cycle 1(1): loaded http://localhost:48609/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48609/tests/kraken/driver.html?testName=ai-astar) 05:56:58 INFO - PROCESS | 18917 | RSS: Main: 251453440 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | __start_tp_report 05:56:58 INFO - PROCESS | 18917 | _x_x_mozilla_page_load 05:56:58 INFO - PROCESS | 18917 | _x_x_mozilla_page_load_details 05:56:58 INFO - PROCESS | 18917 | |i|pagename|runs| 05:56:58 INFO - PROCESS | 18917 | |0;ai-astar;110;112;106;111;110;112;111;110;106;118 05:56:58 INFO - PROCESS | 18917 | |1;audio-beat-detection;153;134;167;168;129;154;150;126;169;126 05:56:58 INFO - PROCESS | 18917 | |2;audio-dft;163;161;164;162;161;162;162;162;163;161 05:56:58 INFO - PROCESS | 18917 | |3;audio-fft;76;74;97;98;73;73;74;114;98;72 05:56:58 INFO - PROCESS | 18917 | |4;audio-oscillator;97;96;138;96;97;96;140;96;96;138 05:56:58 INFO - PROCESS | 18917 | |5;imaging-gaussian-blur;108;109;109;110;110;110;110;109;110;108 05:56:58 INFO - PROCESS | 18917 | |6;imaging-darkroom;147;147;146;147;147;146;147;146;147;146 05:56:58 INFO - PROCESS | 18917 | |7;imaging-desaturate;96;96;95;96;96;96;96;96;96;96 05:56:58 INFO - PROCESS | 18917 | |8;json-parse-financial;57;56;55;55;56;57;58;56;56;54 05:56:58 INFO - PROCESS | 18917 | |9;json-stringify-tinderbox;68;68;69;68;69;75;69;70;69;70 05:56:58 INFO - PROCESS | 18917 | |10;stanford-crypto-aes;82;83;82;82;83;82;82;83;83;82 05:56:58 INFO - PROCESS | 18917 | |11;stanford-crypto-ccm;117;119;121;113;123;118;131;127;126;114 05:56:58 INFO - PROCESS | 18917 | |12;stanford-crypto-pbkdf2;147;134;136;149;133;144;149;154;146;146 05:56:58 INFO - PROCESS | 18917 | |13;stanford-crypto-sha256-iterative;68;67;67;69;68;67;66;69;66;70 05:56:58 INFO - PROCESS | 18917 | __end_tp_report 05:56:58 INFO - PROCESS | 18917 | __start_cc_report 05:56:58 INFO - PROCESS | 18917 | _x_x_mozilla_cycle_collect,953 05:56:58 INFO - PROCESS | 18917 | __end_cc_report 05:56:58 INFO - PROCESS | 18917 | __startTimestamp1453384618502__endTimestamp 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | ------- Summary: start ------- 05:56:58 INFO - PROCESS | 18917 | Number of tests: 14 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:3.37 (3.0%) stddev-sans-first:3.57 05:56:58 INFO - PROCESS | 18917 | Values: 110.0 112.0 106.0 111.0 110.0 112.0 111.0 110.0 106.0 118.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:153.50 stddev:17.61 (11.5%) stddev-sans-first:18.57 05:56:58 INFO - PROCESS | 18917 | Values: 153.0 134.0 167.0 168.0 129.0 154.0 150.0 126.0 169.0 126.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 05:56:58 INFO - PROCESS | 18917 | Values: 163.0 161.0 164.0 162.0 161.0 162.0 162.0 162.0 163.0 161.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#3] audio-fft Cycles:10 Average:84.90 Median:75.00 stddev:15.29 (20.4%) stddev-sans-first:15.87 05:56:58 INFO - PROCESS | 18917 | Values: 76.0 74.0 97.0 98.0 73.0 73.0 74.0 114.0 98.0 72.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:96.00 stddev:20.48 (21.3%) stddev-sans-first:21.26 05:56:58 INFO - PROCESS | 18917 | Values: 97.0 96.0 138.0 96.0 97.0 96.0 140.0 96.0 96.0 138.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:110.00 stddev:0.82 (0.7%) stddev-sans-first:0.73 05:56:58 INFO - PROCESS | 18917 | Values: 108.0 109.0 109.0 110.0 110.0 110.0 110.0 109.0 110.0 108.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 05:56:58 INFO - PROCESS | 18917 | Values: 147.0 147.0 146.0 147.0 147.0 146.0 147.0 146.0 147.0 146.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 05:56:58 INFO - PROCESS | 18917 | Values: 96.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.00 stddev:1.15 (2.1%) stddev-sans-first:1.17 05:56:58 INFO - PROCESS | 18917 | Values: 57.0 56.0 55.0 55.0 56.0 57.0 58.0 56.0 56.0 54.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.00 stddev:2.07 (3.0%) stddev-sans-first:2.12 05:56:58 INFO - PROCESS | 18917 | Values: 68.0 68.0 69.0 68.0 69.0 75.0 69.0 70.0 69.0 70.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 05:56:58 INFO - PROCESS | 18917 | Values: 82.0 83.0 82.0 82.0 83.0 82.0 82.0 83.0 83.0 82.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#11] stanford-crypto-ccm Cycles:10 Average:120.90 Median:122.00 stddev:5.84 (4.8%) stddev-sans-first:6.02 05:56:58 INFO - PROCESS | 18917 | Values: 117.0 119.0 121.0 113.0 123.0 118.0 131.0 127.0 126.0 114.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.80 Median:146.50 stddev:7.08 (4.8%) stddev-sans-first:7.42 05:56:58 INFO - PROCESS | 18917 | Values: 147.0 134.0 136.0 149.0 133.0 144.0 149.0 154.0 146.0 146.0 05:56:58 INFO - PROCESS | 18917 | 05:56:58 INFO - PROCESS | 18917 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.34 (2.0%) stddev-sans-first:1.41 05:56:58 INFO - PROCESS | 18917 | Values: 68.0 67.0 67.0 69.0 68.0 67.0 66.0 69.0 66.0 70.0 05:56:58 INFO - PROCESS | 18917 | -------- Summary: end -------- 05:56:58 INFO - PROCESS | 18917 | 05:56:59 INFO - TEST-INFO | 18917: exit 0 05:56:59 INFO - TEST-OK | kraken | took 59273ms 05:56:59 INFO - TEST-START | v8_7 05:56:59 INFO - operating with platform_type : linux_ 05:56:59 INFO - Initialising browser for v8_7 test... 05:56:59 INFO - TEST-INFO | started process 19168 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmCPQyB/profile http://localhost:48609/getInfo.html) 05:57:03 INFO - PROCESS | 19168 | __metrics Screen width/height:1600/1200 05:57:03 INFO - PROCESS | 19168 | colorDepth:24 05:57:03 INFO - PROCESS | 19168 | Browser inner width/height: 1024/697 05:57:03 INFO - PROCESS | 19168 | __metrics 05:57:03 INFO - PROCESS | 19168 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:57:03 INFO - PROCESS | 19168 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:57:04 INFO - TEST-INFO | 19168: exit 0 05:57:04 INFO - Browser initialized. 05:57:04 INFO - Running cycle 1/1 for v8_7 test... 05:57:04 INFO - TEST-INFO | started process 19249 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmCPQyB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:16 INFO - PROCESS | 19249 | RSS: Main: 148770816 05:57:16 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | Cycle 1(1): loaded http://localhost:48609/tests/v8_7/run.html (next: http://localhost:48609/tests/v8_7/run.html) 05:57:39 INFO - PROCESS | 19249 | RSS: Main: 570298368 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | __start_tp_report 05:57:39 INFO - PROCESS | 19249 | _x_x_mozilla_page_load 05:57:39 INFO - PROCESS | 19249 | _x_x_mozilla_page_load_details 05:57:39 INFO - PROCESS | 19249 | |i|pagename|runs| 05:57:39 INFO - PROCESS | 19249 | |0;Richards;166.86133822793258 05:57:39 INFO - PROCESS | 19249 | |1;DeltaBlue;189.7173211914248 05:57:39 INFO - PROCESS | 19249 | |2;Encrypt;280.19052956010086 05:57:39 INFO - PROCESS | 19249 | |3;Decrypt;5196.891191709845 05:57:39 INFO - PROCESS | 19249 | |4;RayTrace;1986.111111111111 05:57:39 INFO - PROCESS | 19249 | |5;Earley;785.5459544383347 05:57:39 INFO - PROCESS | 19249 | |6;Boyer;12345.67901234568 05:57:39 INFO - PROCESS | 19249 | |7;RegExp;42312.5 05:57:39 INFO - PROCESS | 19249 | |8;Splay;607.1645415907711 05:57:39 INFO - PROCESS | 19249 | |9;NavierStokes;4845.410628019324 05:57:39 INFO - PROCESS | 19249 | __end_tp_report 05:57:39 INFO - PROCESS | 19249 | __startTimestamp1453384659285__endTimestamp 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | ------- Summary: start ------- 05:57:39 INFO - PROCESS | 19249 | Number of tests: 10 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#0] Richards Cycles:1 Average:166.86 Median:166.86 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 166.9 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#1] DeltaBlue Cycles:1 Average:189.72 Median:189.72 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 189.7 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#2] Encrypt Cycles:1 Average:280.19 Median:280.19 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 280.2 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#3] Decrypt Cycles:1 Average:5196.89 Median:5196.89 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 5196.9 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#4] RayTrace Cycles:1 Average:1986.11 Median:1986.11 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 1986.1 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#5] Earley Cycles:1 Average:785.55 Median:785.55 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 785.5 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#6] Boyer Cycles:1 Average:12345.68 Median:12345.68 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 12345.7 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#7] RegExp Cycles:1 Average:42312.50 Median:42312.50 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 42312.5 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#8] Splay Cycles:1 Average:607.16 Median:607.16 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 607.2 05:57:39 INFO - PROCESS | 19249 | 05:57:39 INFO - PROCESS | 19249 | [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 05:57:39 INFO - PROCESS | 19249 | Values: 4845.4 05:57:39 INFO - PROCESS | 19249 | -------- Summary: end -------- 05:57:39 INFO - PROCESS | 19249 | 05:57:40 INFO - TEST-INFO | 19249: exit 0 05:57:40 INFO - TEST-OK | v8_7 | took 40683ms 05:57:40 INFO - SUITE-END | took 1213s 05:57:40 INFO - Completed test suite (00:20:13) 05:57:40 INFO - Working with test: dromaeo_css 05:57:40 INFO - Generating results file: dromaeo_css 05:57:40 INFO - Working with test: kraken 05:57:40 INFO - Generating results file: kraken 05:57:40 INFO - javascript benchmark 05:57:40 INFO - Working with test: v8_7 05:57:40 INFO - Generating results file: v8_7 05:57:40 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:57:40 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 05:57:40 INFO - process_Request line: dromaeo_css 6901.75 graph.html#tests=[[72,1,35]] 05:57:40 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:57:40 INFO - process_Request line: kraken 1506.30 graph.html#tests=[[232,1,35]] 05:57:40 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:57:40 INFO - process_Request line: v8_7 18867.06 graph.html#tests=[[230,1,35]] 05:57:40 INFO - RETURN: dromaeo_css: 6901.75 05:57:40 INFO - RETURN: kraken: 1506.30 05:57:40 INFO - RETURN: v8_7: 18867.06 05:57:40 INFO - javascript benchmark 05:57:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3183.140552050946, 3201.6798320167973, 3245.7403407727384, 3248.658890816464, 3269.6076470823496, 5158.174692049273, 5243.947560524393, 5257.579393648509, 5258.079836028694, 5338.306020217376, 5269.552088072518, 5303.204519322104, 5316.069687804638, 5337.439568845264, 5361.812336568221, 4748.382710247672, 4801.279808028806, 4805.327254184417, 4857.125717370872, 4913.066517361708, 4809.90380192396, 4848.612111031127, 4890.242012488052, 4891.926621100686, 4896.996115796253, 4733.408323959505, 4737.73942433166, 4849.199882019462, 4856.951430485703, 4861.78121984508, 4862.222044472903, 4895.583875370562, 4917.114919314522, 4941.160002799496, 4957.950420495821, 4750.216214324632, 4871.902561948777, 4886.462489126229, 4907.067657145131, 4948.678335908155, 5055.949440505621, 5092.516210959953, 5104.285400043997, 5134.614903882187, 5137.691738495665, 5641.435856414326, 5677.836043611092, 5681.0, 5693.316801983747, 5701.543876489871, 5624.859378515529, 5672.12082127271, 5704.258446401983, 5709.971450142764, 5738.770449182028, 5329.813456529042, 5345.118055520835, 5366.704831234241, 5367.677939323653, 5387.407385187587, 5840.386759390288, 5845.181674565564, 5850.5612079094235, 5854.473097421167, 5896.233489646319, 5524.392316845144, 5592.049351610236, 5631.352394474606, 5634.0, 5638.577106717013, 5612.579056570774, 5670.886582268332, 5701.800436984686, 5713.143028545752, 5755.395683453261, 5604.859878502988, 5647.858803529863, 5659.5755318351285, 5669.06460434032, 5672.546196304368, 5526.364468086141, 5615.101583746581, 5675.545956323567, 5682.829515114553, 5694.202811606378, 4364.803583838735, 4415.094905544356, 4417.801198946056, 4439.311906654474, 4483.439570053743, 3919.5884432134785, 3940.2119576084897, 3963.2667956428154, 3976.701747368959, 3980.7810570418324, 5204.76578553966, 5279.41926388097, 5280.234366016906, 5290.285811415487, 5324.334458192726, 5618.269624948731, 5710.143478478261, 5758.222639943638, 5762.193292938992, 5811.215485909433, 5214.270002199695, 5288.68267903927, 5290.867728306823, 5301.708406037628, 5305.151175811929, 4833.613310935117, 4882.121218180761, 4882.414110306786, 4909.484504127086, 4915.606751459874, 5806.883862322815, 5860.208871802422, 5873.530117590582, 5878.353381128072, 5928.940710592925, 5641.915371269516, 5688.345840228261, 5691.77232910679, 5697.3448053474385, 5707.857303567444, 5756.71216439168, 5881.353051164283, 5890.440408161217, 5897.64614123154, 5911.467967882912, 5606.579506537108, 5657.066584013592, 5668.489835914623, 5669.0, 5689.77240910359, 5677.858053548694, 5704.372519022987, 5726.055200891807, 5734.2258795062135, 5737.48362647347, 5466.753996070107, 5550.333959924835, 5561.138023606348, 5596.776128954796, 5639.2668953035845, 5499.615026948075, 5505.9174112387545, 5518.2826232589505, 5524.972375138099, 5542.140968149944, 5432.266644003167, 5438.075527160313, 5438.293021907127, 5442.0, 5495.313085864267, 5442.891142177214, 5474.945250547444, 5522.97238513821, 5523.088690366045, 5544.974179776754, 5439.782408703766, 5486.972565137149, 5488.0, 5499.835004949858, 5511.118221084607], "unit": "score", "name": "dojo.html", "value": 5243.77896738853}, {"lowerIsBetter": false, "replicates": [533.3759501383381, 556.81903381401, 586.7095787585146, 592.256717819137, 605.1346574398609, 28858.711412885867, 29266.561001584967, 29278.2680432989, 29293.0, 29294.707052929465, 29415.26461838455, 35995.2800943981, 36586.817065914634, 36640.816795915984, 36662.816685916536, 8329.83340333193, 8467.364947628921, 8483.74548763538, 8490.235878770909, 8518.87221691675, 8523.57382130891, 8698.086700896378, 8707.129287071273, 8714.25928796053, 8714.651413943466, 8658.653653853838, 8743.781405464852, 8753.168448997336, 8754.956225218864, 8760.780980475507, 8409.95795021024, 8458.74623761288, 8459.238668519863, 8463.873041904377, 8470.66117355308, 8393.790155246106, 8551.914480855174, 8560.61477233526, 8572.485650860968, 8573.52845593492, 38108.23783524342, 38698.80650596757, 39107.217855642724, 39120.21759564821, 39130.0, 3123.7657175711684, 3322.6178989416044, 3733.4959780429567, 3742.0, 3824.9426258606145, 28896.254855788666, 29095.41809163826, 29112.0, 30497.0, 30707.385852283052, 3544.113971507123, 3551.4317709166407, 3566.7681600696133, 3663.5603727552866, 3725.217704282104, 253630.0, 254024.99999999814, 254418.0, 255696.0, 256530.0, 48857.511424886005, 49093.50906490961, 50477.24284135741, 51013.23480147801, 57944.654150938004, 51456.22815657768, 51544.74227628838, 52123.739381302854, 52362.476375236525, 52415.475845241825, 14942.0, 15059.548213553611, 15103.54689359321, 15348.309326080353, 15348.309326080353, 10626.043656070837, 10751.51618177184, 11100.833487497694, 11164.888351116548, 11228.943855280671, 18183.272669093352, 18216.544586385182, 18294.54263643393, 18337.266509339475, 18404.907975460035, 7082.220955694869, 7152.569486102696, 7157.89263161053, 7271.527350722292, 7311.159216690149, 1027.3989716016065, 1064.9041586257274, 1136.7044568412273, 1143.6511863881597, 1157.9826302605468, 28471.28821779472, 28505.857470712515, 29601.111966641034, 30665.233369165504, 31343.37313253722, 1015.1016350529834, 1084.1489430796787, 1084.2356138922041, 1087.6573879228017, 1113.5434471866495, 14654.19401932904, 14661.85338146605, 14869.0, 15114.697706045818, 15148.242587870562, 283673.0, 290401.0, 291299.0, 291350.0, 292325.0, 53942.46057539453, 54300.45699542954, 54739.0, 54854.17718734222, 54964.0, 1388.9097208681605, 1398.8111604933408, 1403.031907983488, 1407.5777266819914, 1410.2737090398452, 25872.353191170372, 28340.57489137706, 29205.0, 29745.405091897606, 30829.0, 49386.259206111215, 50168.74915625399, 50222.7488862568, 50279.49720502748, 50415.49584504255, 241231.0, 243441.0, 244655.0, 245367.0, 245581.0, 28257.57613635756, 28885.56671649969, 32241.193970149994, 32369.0, 32830.34339313152, 37029.44455833219, 37410.0, 37515.0, 37633.623663764105, 37879.62120378761, 30313.696863031088, 31319.874848043495, 31998.36717626067, 34162.82918585391, 34522.0, 34233.82883085569, 34878.302433950674, 34925.65074349224, 35061.82469087638, 35100.64899350974, 34662.82668586641, 34717.82641086778, 37824.0, 38312.42531361976, 38932.221355573296, 30807.69192308048, 32837.83581082079, 33091.66908330982, 33123.0, 33140.668593313756, 24608.876955615106, 24975.625365619166, 25397.619035714848, 25513.74486255188, 27128.0], "unit": "score", "name": "ext.html", "value": 17780.705541200958}, {"lowerIsBetter": false, "replicates": [371.05381277741753, 374.730194260133, 374.8631749411464, 375.79894756305373, 378.4550247643393, 13827.861721382784, 14339.21134337611, 15319.770203446957, 17042.403515876947, 17511.474655760347, 11072.114230861533, 11288.379139147344, 11318.32090074596, 11319.434028298594, 17089.0, 4364.25807612705, 5908.1728558001805, 6110.388961103877, 6112.938870611304, 6120.4797592204495, 4495.505494395614, 6536.215654121512, 6758.763443279487, 6767.796966091, 6769.7630582929605, 6497.350264973488, 6803.013563033382, 6811.557248778813, 6811.761588344409, 6821.761238356658, 6591.934080659204, 6697.0, 6720.899186512206, 6919.100516932817, 6931.584104953668, 6326.304106548276, 6552.901706474407, 6599.3070727573395, 6619.966900165517, 6621.834454138637, 21941.0, 22003.779962200333, 22412.43968900774, 22878.31365059051, 22904.312870613907, 2313.8727369994645, 2324.7442781294044, 2447.7919376852988, 2558.475512519929, 2805.859707014641, 13475.460981560725, 13813.378397972116, 13870.930645346809, 14110.788338174936, 14400.423983040666, 1794.7128459446426, 1918.0, 1918.3015265287531, 1937.7674679038605, 2000.339887837025, 61824.69087654578, 63139.0, 64478.35521644771, 64584.67707661479, 65094.0, 14587.926822001064, 15052.0, 15089.396424143131, 15095.924520377328, 15502.5349239523, 23494.88252558726, 23613.173538926054, 23855.0, 23950.64074038891, 24007.159749409035, 6746.4265537429865, 7065.2228254891925, 7129.500934934505, 7244.311790379906, 8664.523451210118, 6719.899201511981, 6770.796876093725, 6784.728610855511, 6801.285864984205, 6810.387065164159, 4245.724027938236, 4337.631301339421, 4403.471583410012, 4504.436945381827, 4523.796429160696, 1839.8620103492024, 1847.3811273223353, 1873.4286042756817, 1877.080230686981, 1877.0896115384028, 808.9726047919228, 896.5651658945407, 947.2422062350092, 1011.7521207304111, 1045.242199405425, 14699.412023519153, 15287.312070956834, 16028.839711602735, 16161.838381616033, 16573.585660358345, 957.913787759105, 959.7696552827272, 972.4214092614883, 979.0160888307206, 1016.6441745389203, 9108.089191080839, 9368.67209647659, 9380.343375963617, 9515.33392662507, 9767.51162441875, 528585.0, 529317.0, 534372.0, 545938.0, 548881.0, 52877.0, 55792.0, 70945.64527177434, 71882.2811771886, 72920.63539682268, 1014.3305418423805, 1045.383223897904, 1046.4924511611862, 1108.6507750058706, 1158.438157493598, 14411.711765764836, 14691.11853288806, 14727.190004549853, 15282.923585382447, 15327.157006364758, 30218.84890575533, 33233.83383083069, 33613.0, 34368.65631343654, 34485.655143448246, 34767.82616086904, 35757.64242357543, 36056.4591531122, 36092.0, 38207.426888596136, 4659.394278743742, 4906.9018619626695, 7500.737474188377, 7557.2820582044615, 7627.580483073484, 9895.257855660999, 10736.570537178737, 11125.554977800797, 11186.32882027081, 11548.480318385526, 8896.688615898413, 9145.954270228607, 9323.114304141096, 9434.905650943678, 9460.0, 9642.42145471274, 9653.324267301221, 9697.127258546483, 10022.498875056364, 10207.183425326104, 7415.555066696015, 9265.490398028172, 9467.431954082776, 9583.185429238592, 9583.760405989906, 7528.171901090986, 7609.429292803173, 7638.388928885564, 7668.961655191688, 7669.769906902578, 8577.742667719978, 9259.444433334022, 9387.249020078241, 9497.572609232739, 9551.522423878918], "unit": "score", "name": "jquery.html", "value": 8583.985675772661}, {"lowerIsBetter": false, "replicates": [1360.129517109051, 1464.619199008258, 1476.9483068092616, 1621.5946013496625, 1815.5824160443099, 6990.440764738822, 7704.306612404883, 7844.764657060283, 7845.09781375142, 7865.764027079183, 7696.307332340089, 7772.96113519433, 7854.332381747558, 7860.68557257709, 7862.606869656523, 2057.763357213917, 2092.884891330976, 2350.8589484630893, 2383.1182462488905, 2416.758324167578, 2394.1141777542334, 2394.5330660521204, 2398.8920498577604, 2400.663907053014, 2401.6397540369, 2330.93007209784, 2390.784829365357, 2396.760323967607, 2397.8081753459683, 2399.8320117591775, 2338.7895089441945, 2364.798992085675, 2388.3551441110803, 2392.437777122375, 2394.4133687246563, 2353.446939969106, 2355.281639100074, 2382.988085059581, 2389.689340385756, 2402.3633737059695, 7813.0, 7845.0, 7857.489263197931, 7858.8428231435055, 7873.094594121692, 4904.166291730415, 5013.749312534395, 5044.39467263927, 5053.418856831475, 5062.417821950486, 3289.210589458513, 3294.1435226841195, 3363.3441478911623, 3391.5930088389305, 3406.9659303407143, 1045.8692663417073, 1068.1881769854888, 1089.4824958144898, 1092.5684354679936, 1102.7132945434164, 11156.776864462747, 11199.496022678999, 11200.383978881158, 11205.775884482346, 11222.775544489064, 4582.198115329804, 4595.448546174481, 4655.11552804966, 4660.370849935283, 4661.044485880387, 9456.8581471278, 9483.430994140374, 9492.050794920453, 9511.952440237754, 9512.762180945394, 5036.823711170092, 5043.773030213649, 5047.268146118792, 5069.923951140736, 5076.796928122833, 4615.100055489214, 4621.745803980813, 4629.490756016836, 4631.8378856739855, 4633.559811817872, 3522.330757156132, 3614.0964758810296, 3790.2608991246443, 3824.388097904322, 3863.517060367454, 1267.2206592945272, 1269.8730126987227, 1277.974440511203, 1279.4945996331583, 1288.7293668329569, 1052.1057101463848, 1078.978420431587, 1080.400377790318, 1080.6974047266779, 1146.4611632532697, 7512.812179695551, 7551.207123252089, 7599.810004749815, 7640.541567505968, 7654.158042615308, 1119.9664010079712, 1127.1490025031212, 1136.9943150284362, 1139.2082502660571, 1154.2151337090859, 5605.411431799684, 5668.489835914787, 5677.233573467612, 5685.317761868602, 5700.914486282709, 9653.22774178064, 9719.0, 9788.314817962817, 9871.259655525864, 9899.30704850668, 9719.951400243095, 9726.75683107928, 9732.172765315028, 9758.072983066597, 9786.902130978742, 7355.558666479921, 7570.772876813704, 7607.049118860143, 7614.657340419694, 7639.350655194371, 7257.237990010973, 7294.708211671474, 7301.08736407949, 7302.123745150616, 7355.411567074514, 8510.106438823834, 8590.484570925764, 8783.165599268059, 8805.559722013746, 8933.19601235892, 8804.779880503038, 8931.553422328987, 8944.910550894409, 9101.317401195069, 9101.499417531835, 6337.7781777637565, 7082.752103676346, 7109.182444019004, 7165.211826698955, 7181.569105853666, 6967.268436814062, 7351.228121047427, 7555.09338879338, 7559.168491465824, 7573.886391704239, 7001.894971575329, 7096.964515177391, 7162.033125527987, 7171.677274522554, 7281.708731650675, 7162.820929476805, 7193.028941092885, 7213.891791623025, 7220.819479512844, 7327.816804579928, 6947.444204463732, 6989.580625162506, 7002.649867506706, 7151.964240178766, 7157.320054594805, 6603.108580341785, 6646.9335306648245, 6650.135482387259, 6657.933420665925, 6660.134182556237, 6616.371444712745, 6659.167604049494, 6670.733170673118, 6711.228208756055, 6713.1944166700305], "unit": "score", "name": "mootools.html", "value": 4636.3803229475}, {"lowerIsBetter": false, "replicates": [334.9363620912027, 345.9948100778488, 359.12732061091555, 359.8610398090045, 365.0654324928184, 5697.202391665171, 5869.442402971717, 5870.589058765888, 5882.529397648189, 5906.911396329058, 5636.351819540754, 5778.162166485868, 5808.3320418151925, 5835.737391817368, 5838.124281357798, 4565.977170114144, 4569.223232050559, 4578.633709303264, 4580.541945805409, 4582.335561343603, 4394.121175764843, 4474.619657329131, 4494.820207191725, 4561.7262964222255, 4595.862124136264, 4531.592156705896, 4545.545445455445, 4546.204414227497, 4554.521775213605, 4564.42944631921, 4529.682922195448, 4547.567981041812, 4556.726596404226, 4556.817727290904, 4563.361129441881, 4045.2516284487465, 4050.7164498485117, 4052.0, 4054.7161698681102, 4061.614146656063, 5439.864003399907, 5576.163575463673, 5576.637518561281, 5581.079121944874, 5607.130894711286, 5215.608829337815, 5225.294585230983, 5227.581793456515, 5230.163173892159, 5231.189165679326, 5915.142304365886, 6238.9376106238815, 6239.532035097341, 6243.8126856194385, 6244.094606282109, 4288.142371525707, 4288.656907447428, 4386.385905973167, 4394.824207031715, 4404.603585677272, 6402.583832050902, 6452.64510451925, 6455.644939528371, 6463.224413070415, 6485.286618471964, 4474.574915383034, 4489.9551004490195, 4490.640748740093, 4495.865124046284, 4501.752403617833, 4443.0, 4558.954410455919, 4563.475200351935, 4568.246239370533, 4579.198640237945, 5224.425313215544, 5232.037985755342, 5238.71187084714, 5239.0, 5246.108161612535, 6237.719302631393, 6239.376062393724, 6250.4374606285655, 6302.4958003359625, 6318.62088274705, 1568.5764843492193, 1605.0289574807073, 1607.5338151936041, 1610.2995197089303, 1611.0575313441534, 1077.1006209814736, 1080.897314755097, 1092.049916572571, 1099.780043991205, 1115.2026301194685, 5917.7928772493615, 5930.703464826742, 5931.051031834972, 5934.495567876779, 5938.435848594377, 6406.071119687619, 6407.519436042316, 6422.261439934374, 6432.2281326241155, 6433.388828061326, 5906.497947674496, 5913.615614985012, 5922.289325280994, 5930.258717660293, 5934.6142500737315, 3372.0, 3462.065242384542, 3465.2203254268093, 3470.236547959445, 3472.166679996833, 6637.601743895381, 6656.667166641649, 6662.333766623396, 6669.1330127083165, 6681.33186681328, 6525.412712855866, 6584.176977877765, 6589.978553324242, 6592.571482853599, 6604.471642268607, 6482.513811464159, 6631.204255489372, 6637.601743895381, 6638.734450622114, 6638.805015097329, 6190.938090619036, 6339.778107766206, 6437.195350581177, 6447.323031081669, 6462.773802916875, 6216.502679785516, 6339.96830015847, 6372.904406434, 6374.362563743589, 6381.1704478417505, 6578.111954886029, 6634.800955971328, 6635.900461493178, 6637.103990961352, 6640.203175618763, 4441.133978874218, 4478.843240486567, 4521.61566266871, 4524.298733696282, 4525.570070843265, 6368.840778980562, 6389.073584330339, 6393.041043843461, 6398.808035758934, 6399.8080057598345, 6247.843803904939, 6256.249250090018, 6258.718357673824, 6259.749610015549, 6269.529785266214, 6184.072389141665, 6251.093591429127, 6302.936970630235, 6333.493320534254, 6337.04944258365, 6117.96941015292, 6142.57002009855, 6145.815625531062, 6153.769246150699, 6154.292256390572, 6226.25284965807, 6285.905711414241, 6286.5599408041, 6294.496440284858, 6313.747450101944, 5987.221661184019, 6127.172831667668, 6145.569810113249, 6150.446459818638, 6153.13856060143], "unit": "score", "name": "prototype.html", "value": 4729.08784915124}, {"lowerIsBetter": false, "replicates": [736.6721808795087, 743.6950850151437, 762.713982256654, 774.8605251054807, 786.830831371255, 13231.139975901571, 13550.728985420297, 13580.117292375988, 15419.15194664293, 15773.60565985851, 14168.291585420739, 14178.511256318066, 14213.431462741504, 14318.856811431882, 14342.71314573708, 675.797260821754, 683.9555428897131, 815.3477218225411, 830.4353039932844, 830.9127541608104, 807.1726480357622, 821.2690705272313, 828.5194587139445, 829.8049958259804, 830.0495932157676, 858.6393714639867, 858.9742307730747, 860.096898256831, 863.7365603490932, 865.0873328638285, 697.9825504362381, 702.9841878485565, 852.7569642651846, 857.5155037403847, 860.8622620380709, 630.3224034163293, 630.6941133550224, 631.2235949781748, 631.9210098737658, 633.4678869749387, 13473.326333683375, 13495.932520337336, 13561.321933903266, 13564.45742170312, 13590.38843252056, 12229.205101668453, 12421.31682757448, 12493.625191244277, 12521.186122901981, 12930.030247731363, 10459.0, 10735.83896241557, 10763.0, 10877.12982961369, 11008.119350451945, 3600.4959305697225, 3679.5400574928135, 3695.260947810422, 3777.395616701315, 3782.9432558511644, 43179.13641727148, 43514.782426087666, 43522.56477435217, 43548.564514354766, 43550.782246088565, 9803.532052682822, 10123.696289111338, 10133.18934485254, 10141.847872281922, 10339.483025848678, 17461.38885139014, 17498.91250543739, 17587.648247034987, 17612.471625851245, 17656.558586035197, 7188.568685878759, 7298.96350518244, 7396.482246242819, 7490.250974902466, 7582.469227154156, 4162.334026555736, 4177.811998460076, 4187.392828039978, 4217.936730949038, 4312.439382880206, 3228.580284562992, 3272.9018129456153, 3275.3449310138067, 3276.0499455158215, 3282.967170328314, 614.757170917485, 614.8647297594522, 620.4447019917149, 634.3624657219465, 651.8077167235676, 532.2495281652851, 548.9934290169438, 603.2670305578743, 637.7863415755774, 707.3669066185735, 8175.672973081129, 8402.663893444316, 8542.359323050796, 8586.22723954847, 8623.396362254582, 584.7865529081939, 585.4233579923772, 638.8981494842566, 712.9484011083714, 713.1014921199326, 4022.8591999279884, 4102.446169767103, 4169.97915010423, 4180.937285940713, 4242.490901091889, 51405.0, 52032.73983630058, 52892.0, 53337.0, 53532.0, 45755.0, 46473.535264647595, 46951.29573056407, 46993.0, 47304.7634761824, 39559.80220098938, 40747.18505629871, 41482.22183353763, 41635.75463680472, 42216.57783422188, 8033.437659364022, 8101.797455063671, 8244.422890397615, 8263.913351955547, 8317.916820831715, 31225.219369515944, 31494.685053149176, 31523.527147092354, 32304.35391292208, 32651.0, 37829.621703782606, 38232.0, 38321.4251786229, 41796.79101604473, 42392.0, 10196.643117490852, 10254.333468324536, 10259.94870025675, 10365.63720269787, 10924.235303528676, 11235.775284494428, 11346.716332091764, 11398.715032124263, 11577.247478913843, 11635.301881887113, 10584.629537966135, 10631.893681063091, 10749.247552671239, 10830.566777328819, 11279.548818047186, 10103.797924041331, 10138.543765530716, 10353.0, 10390.480475976323, 10443.373397596168, 8261.413663989464, 10143.797124057624, 10421.53103110377, 11139.554417823521, 11544.826827597424, 9399.812003760024, 9548.61805527799, 9711.562979666074, 9756.51217439111, 9945.900540994788, 9328.626854925727, 9794.608215671293, 9810.215182785218, 9880.258980576342, 9960.501974901372], "unit": "score", "name": "yui.html", "value": 6158.586041552263}], "name": "dromaeo_css", "value": 6901.752011446161}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 112.0, 106.0, 111.0, 110.0, 112.0, 111.0, 110.0, 106.0, 118.0], "unit": "score", "name": "ai-astar", "value": 110.6}, {"lowerIsBetter": true, "replicates": [153.0, 134.0, 167.0, 168.0, 129.0, 154.0, 150.0, 126.0, 169.0, 126.0], "unit": "score", "name": "audio-beat-detection", "value": 147.6}, {"lowerIsBetter": true, "replicates": [163.0, 161.0, 164.0, 162.0, 161.0, 162.0, 162.0, 162.0, 163.0, 161.0], "unit": "score", "name": "audio-dft", "value": 162.1}, {"lowerIsBetter": true, "replicates": [76.0, 74.0, 97.0, 98.0, 73.0, 73.0, 74.0, 114.0, 98.0, 72.0], "unit": "score", "name": "audio-fft", "value": 84.9}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 138.0, 96.0, 97.0, 96.0, 140.0, 96.0, 96.0, 138.0], "unit": "score", "name": "audio-oscillator", "value": 109.0}, {"lowerIsBetter": true, "replicates": [108.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 108.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 55.0, 55.0, 56.0, 57.0, 58.0, 56.0, 56.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 56.0}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 68.0, 69.0, 75.0, 69.0, 70.0, 69.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.5}, {"lowerIsBetter": true, "replicates": [82.0, 83.0, 82.0, 82.0, 83.0, 82.0, 82.0, 83.0, 83.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.4}, {"lowerIsBetter": true, "replicates": [117.0, 119.0, 121.0, 113.0, 123.0, 118.0, 131.0, 127.0, 126.0, 114.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.9}, {"lowerIsBetter": true, "replicates": [147.0, 134.0, 136.0, 149.0, 133.0, 144.0, 149.0, 154.0, 146.0, 146.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.8}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 67.0, 69.0, 68.0, 67.0, 66.0, 69.0, 66.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.7}], "name": "kraken", "value": 1506.3000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.86133822793258], "unit": "score", "name": "Richards", "value": 211.56488599999994}, {"lowerIsBetter": false, "replicates": [189.7173211914248], "unit": "score", "name": "DeltaBlue", "value": 348.507978}, {"lowerIsBetter": false, "replicates": [280.19052956010086], "unit": "score", "name": "Encrypt", "value": 949.9999890000003}, {"lowerIsBetter": false, "replicates": [5196.891191709845], "unit": "score", "name": "Decrypt", "value": 51.219275174476564}, {"lowerIsBetter": false, "replicates": [1986.111111111111], "unit": "score", "name": "RayTrace", "value": 372.5818741258742}, {"lowerIsBetter": false, "replicates": [785.5459544383347], "unit": "score", "name": "Earley", "value": 848.4073989999999}, {"lowerIsBetter": false, "replicates": [12345.67901234568], "unit": "score", "name": "Boyer", "value": 53.98350299999995}, {"lowerIsBetter": false, "replicates": [42312.5], "unit": "score", "name": "RegExp", "value": 21.52992614475626}, {"lowerIsBetter": false, "replicates": [607.1645415907711], "unit": "score", "name": "Splay", "value": 134.21567700000003}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 18867.059406293985}]} 05:57:40 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "18867.06"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1506.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6901.75"}}} 05:57:40 INFO - Return code: 0 05:57:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:57:40 INFO - # TBPL SUCCESS # 05:57:40 INFO - Running post-action listener: _resource_record_post_action 05:57:40 INFO - Running post-run listener: _resource_record_post_run 05:57:41 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 4096; Write bytes: 523329536; Read time: 12; Write time: 499840 05:57:41 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 230965248; Read time: 0; Write time: 308172 05:57:41 INFO - run-tests - Wall time: 1215s; CPU: 13.0%; Read bytes: 4096; Write bytes: 286097408; Read time: 12; Write time: 189556 05:57:41 INFO - Running post-run listener: _upload_blobber_files 05:57:41 INFO - Blob upload gear active. 05:57:41 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 05:57:41 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 05:57:41 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'] 05:57:41 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 05:57:42 INFO - (blobuploader) - INFO - Open directory for files ... 05:57:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 05:57:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:57:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:43 INFO - (blobuploader) - INFO - Done attempting. 05:57:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:57:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:57:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:57:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:57:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:57:43 INFO - (blobuploader) - INFO - Done attempting. 05:57:43 INFO - (blobuploader) - INFO - Iteration through files over. 05:57:43 INFO - Return code: 0 05:57:43 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 05:57:43 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 05:57:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86de720036388434f64df4bc7f6b311a711ffc99df673f620e514ec231d025d8e40c0cb59c886af1ab89a8321241a8a44a463c4c6d18c97d0d7d91cfed5b0532", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72cd4eaf4a83c716c7f011be1c2b12d96b191df118f2463f1170918de998475399928b3ab844d10557f07dfcdcf06d2a255fc3fd5ddd49b47c5d64b562365ab5"} 05:57:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 05:57:43 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 05:57:43 INFO - Contents: 05:57:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86de720036388434f64df4bc7f6b311a711ffc99df673f620e514ec231d025d8e40c0cb59c886af1ab89a8321241a8a44a463c4c6d18c97d0d7d91cfed5b0532", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72cd4eaf4a83c716c7f011be1c2b12d96b191df118f2463f1170918de998475399928b3ab844d10557f07dfcdcf06d2a255fc3fd5ddd49b47c5d64b562365ab5"} 05:57:43 INFO - Running post-run listener: copy_logs_to_upload_dir 05:57:43 INFO - Copying logs to upload dir... 05:57:43 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 05:57:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.202218 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-01-21 05:57:43.661602) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-21 05:57:43.666061) ========= 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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 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/86de720036388434f64df4bc7f6b311a711ffc99df673f620e514ec231d025d8e40c0cb59c886af1ab89a8321241a8a44a463c4c6d18c97d0d7d91cfed5b0532", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72cd4eaf4a83c716c7f011be1c2b12d96b191df118f2463f1170918de998475399928b3ab844d10557f07dfcdcf06d2a255fc3fd5ddd49b47c5d64b562365ab5"} build_url:https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011419 build_url: 'https://queue.taskcluster.net/v1/task/Vo_nqZB8SVSaKYqve199EA/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/86de720036388434f64df4bc7f6b311a711ffc99df673f620e514ec231d025d8e40c0cb59c886af1ab89a8321241a8a44a463c4c6d18c97d0d7d91cfed5b0532", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72cd4eaf4a83c716c7f011be1c2b12d96b191df118f2463f1170918de998475399928b3ab844d10557f07dfcdcf06d2a255fc3fd5ddd49b47c5d64b562365ab5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-21 05:57:43.693998) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:57:43.694460) ========= 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=52faae308a556c440401f8e5000001e8-1453383220.840016-2130310483 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004655 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 05:57:43.776275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 05:57:43.777553) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 05:57:43.777880) ========= ========= Total master_lag: 0.27 =========