builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-014 starttime: 1451438429.15 results: success (0) buildid: 20151229143009 builduid: 95ed1c357c42473191a4e5474efead95 revision: 8ea3973329af0806c3621300e89ab996eb640c1a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.154753) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.155443) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.156426) ========= 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=01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006269 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.188956) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.189460) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.225460) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.225803) ========= 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=01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004763 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.274916) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.275190) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.275563) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.275838) ========= 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=01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-29 17:20:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2015-12-29 17:20:30 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094783 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.384397) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.384867) ========= 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=01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022524 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.439816) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:29.440148) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 8ea3973329af0806c3621300e89ab996eb640c1a --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 8ea3973329af0806c3621300e89ab996eb640c1a --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=01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-29 17:20:30,329 truncating revision to first 12 chars 2015-12-29 17:20:30,329 Setting DEBUG logging. 2015-12-29 17:20:30,329 attempt 1/10 2015-12-29 17:20:30,329 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/8ea3973329af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-29 17:20:30,572 unpacking tar archive at: b2g-inbound-8ea3973329af/testing/mozharness/ program finished with exit code 0 elapsedTime=0.546086 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:30.000528) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:30.000856) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:30.010224) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:30.010524) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-29 17:20:30.010952) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2015-12-29 17:20:30.011247) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 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', 'B2G-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound'] 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=01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:20:30 INFO - MultiFileLogger online at 20151229 17:20:30 in /builds/slave/test-pgo 17:20:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 17:20:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 17:20:30 INFO - {'append_to_log': False, 17:20:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 17:20:30 INFO - 'blob_upload_branch': 'B2G-Inbound', 17:20:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 17:20:30 INFO - 'branch': 'B2G-Inbound', 17:20:30 INFO - 'buildbot_json_path': 'buildprops.json', 17:20:30 INFO - 'config_files': ('talos/linux_config.py',), 17:20:30 INFO - 'default_actions': ('clobber', 17:20:30 INFO - 'read-buildbot-config', 17:20:30 INFO - 'download-and-extract', 17:20:30 INFO - 'populate-webroot', 17:20:30 INFO - 'create-virtualenv', 17:20:30 INFO - 'install', 17:20:30 INFO - 'run-tests'), 17:20:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:20:30 INFO - 'download_minidump_stackwalk': True, 17:20:30 INFO - 'download_symbols': 'ondemand', 17:20:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:20:30 INFO - 'tooltool.py': '/tools/tooltool.py', 17:20:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:20:30 INFO - '/tools/misc-python/virtualenv.py')}, 17:20:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:20:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:20:30 INFO - 'installer_path': 'installer.exe', 17:20:30 INFO - 'log_level': 'info', 17:20:30 INFO - 'log_name': 'talos', 17:20:30 INFO - 'log_to_console': True, 17:20:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:20:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:20:30 INFO - 'opt_config_files': (), 17:20:30 INFO - 'pip_index': False, 17:20:30 INFO - 'sps_profile': False, 17:20:30 INFO - 'sps_profile_interval': 0, 17:20:30 INFO - 'suite': 'dromaeojs', 17:20:30 INFO - 'system_bits': '32', 17:20:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:20:30 INFO - 'title': 'talos-linux64-ix-014', 17:20:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:20:30 INFO - 'use_talos_json': True, 17:20:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 17:20:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:20:30 INFO - 'work_dir': 'build'} 17:20:30 INFO - ##### 17:20:30 INFO - ##### Running clobber step. 17:20:30 INFO - ##### 17:20:30 INFO - Running pre-action listener: _resource_record_pre_action 17:20:30 INFO - Running main action method: clobber 17:20:30 INFO - rmtree: /builds/slave/test-pgo/build 17:20:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 17:20:31 INFO - Running post-action listener: _resource_record_post_action 17:20:31 INFO - ##### 17:20:31 INFO - ##### Running read-buildbot-config step. 17:20:31 INFO - ##### 17:20:31 INFO - Running pre-action listener: _resource_record_pre_action 17:20:31 INFO - Running main action method: read_buildbot_config 17:20:31 INFO - Using buildbot properties: 17:20:31 INFO - { 17:20:31 INFO - "project": "", 17:20:31 INFO - "product": "firefox", 17:20:31 INFO - "script_repo_revision": "production", 17:20:31 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 17:20:31 INFO - "repository": "", 17:20:31 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 17:20:31 INFO - "buildid": "20151229143009", 17:20:31 INFO - "pgo_build": "True", 17:20:31 INFO - "basedir": "/builds/slave/test-pgo", 17:20:31 INFO - "buildnumber": 163, 17:20:31 INFO - "slavename": "talos-linux64-ix-014", 17:20:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:20:31 INFO - "platform": "ubuntu64_hw", 17:20:31 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 17:20:31 INFO - "branch": "b2g-inbound", 17:20:31 INFO - "repo_path": "integration/b2g-inbound", 17:20:31 INFO - "revision": "8ea3973329af0806c3621300e89ab996eb640c1a", 17:20:31 INFO - "stage_platform": "linux64-pgo", 17:20:31 INFO - "builduid": "95ed1c357c42473191a4e5474efead95", 17:20:31 INFO - "slavebuilddir": "test" 17:20:31 INFO - } 17:20:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 17:20:31 INFO - Running post-action listener: _resource_record_post_action 17:20:31 INFO - ##### 17:20:31 INFO - ##### Running download-and-extract step. 17:20:31 INFO - ##### 17:20:31 INFO - Running pre-action listener: _resource_record_pre_action 17:20:31 INFO - Running main action method: download_and_extract 17:20:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 17:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:31 INFO - https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:20:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/test_packages.json 17:20:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/test_packages.json 17:20:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 17:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 17:20:35 INFO - Downloaded 1302 bytes. 17:20:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 17:20:35 INFO - Using the following test package requirements: 17:20:35 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:20:35 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:20:35 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:35 INFO - u'jsshell-linux-x86_64.zip'], 17:20:35 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:20:35 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:20:35 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:20:35 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:20:35 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:20:35 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 17:20:35 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 17:20:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:20:35 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:20:35 INFO - https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:20:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:20:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:20:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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 17:20:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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 17:20:39 INFO - Downloaded 23192117 bytes. 17:20:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 17:20:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:20:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:20:40 INFO - Return code: 0 17:20:40 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 17:20:40 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')]} 17:20:40 INFO - https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:20:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:20:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:20:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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 17:20:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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 17:20:41 INFO - Downloaded 23192117 bytes. 17:20:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 17:20:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 17:20:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 17:20:43 INFO - Return code: 0 17:20: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')]} 17:20:43 INFO - https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:20:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:20:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:20:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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 17:20:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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 17:20:45 INFO - Downloaded 11132270 bytes. 17:20:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 17:20:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 17:20:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 17:20:45 INFO - Return code: 0 17:20: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')]} 17:20:45 INFO - https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:20:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:20:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:20:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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 17:20:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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 17:20:49 INFO - Downloaded 54902651 bytes. 17:20:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:20:49 INFO - mkdir: /builds/slave/test-pgo/properties 17:20:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 17:20:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 17:20:49 INFO - Contents: 17:20:49 INFO - build_url:https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 17:20:49 INFO - Running post-action listener: _resource_record_post_action 17:20:49 INFO - Running post-action listener: set_extra_try_arguments 17:20:49 INFO - ##### 17:20:49 INFO - ##### Running populate-webroot step. 17:20:49 INFO - ##### 17:20:49 INFO - Running pre-action listener: _resource_record_pre_action 17:20:49 INFO - Running main action method: populate_webroot 17:20:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:20:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:20:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:20:49 INFO - '--tppagecycles', 17:20:49 INFO - '7'], 17:20:49 INFO - 'tests': ['tsvgm']}}, 17:20:49 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:20:49 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:20:49 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:20:49 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:20:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:49 INFO - 'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:20:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:49 INFO - 'tests': ['damp', 'tps']}, 17:20:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:49 INFO - 'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['damp', 'tps']}, 17:20:49 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:20:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:20:49 INFO - 'other': {'tests': ['a11yr', 17:20:49 INFO - 'ts_paint', 17:20:49 INFO - 'tpaint', 17:20:49 INFO - 'sessionrestore', 17:20:49 INFO - 'sessionrestore_no_auto_restore']}, 17:20:49 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['a11yr', 17:20:49 INFO - 'ts_paint', 17:20:49 INFO - 'tpaint', 17:20:49 INFO - 'sessionrestore', 17:20:49 INFO - 'sessionrestore_no_auto_restore']}, 17:20:49 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['a11yr', 17:20:49 INFO - 'ts_paint', 17:20:49 INFO - 'tpaint', 17:20:49 INFO - 'sessionrestore', 17:20:49 INFO - 'sessionrestore_no_auto_restore']}, 17:20:49 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['a11yr', 17:20:49 INFO - 'ts_paint', 17:20:49 INFO - 'tpaint', 17:20:49 INFO - 'sessionrestore', 17:20:49 INFO - 'sessionrestore_no_auto_restore']}, 17:20:49 INFO - 'other_l64': {'tests': ['a11yr', 17:20:49 INFO - 'ts_paint', 17:20:49 INFO - 'tpaint', 17:20:49 INFO - 'sessionrestore', 17:20:49 INFO - 'sessionrestore_no_auto_restore']}, 17:20:49 INFO - 'other_nol64': {'tests': ['a11yr', 17:20:49 INFO - 'ts_paint', 17:20:49 INFO - 'tpaint', 17:20:49 INFO - 'sessionrestore', 17:20:49 INFO - 'sessionrestore_no_auto_restore']}, 17:20:49 INFO - 'svgr': {'tests': ['tsvgx', 17:20:49 INFO - 'tsvgr_opacity', 17:20:49 INFO - 'tart', 17:20:49 INFO - 'tscrollx', 17:20:49 INFO - 'cart']}, 17:20:49 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['tsvgx', 17:20:49 INFO - 'tsvgr_opacity', 17:20:49 INFO - 'tart', 17:20:49 INFO - 'tscrollx', 17:20:49 INFO - 'cart']}, 17:20:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:49 INFO - 'tests': ['tp5o']}, 17:20:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:49 INFO - 'talos_options': ['--e10s'], 17:20:49 INFO - 'tests': ['tp5o']}, 17:20:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:49 INFO - 'talos_options': ['--xperf_path', 17:20:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:49 INFO - 'tests': ['tp5n']}, 17:20:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:20:49 INFO - 'talos_options': ['--e10s', 17:20:49 INFO - '--xperf_path', 17:20:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:20:49 INFO - 'tests': ['tp5n']}}, 17:20:49 INFO - 'talos.zip': {'path': '', 17:20:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:20:49 INFO - Running post-action listener: _resource_record_post_action 17:20:49 INFO - ##### 17:20:49 INFO - ##### Running create-virtualenv step. 17:20:49 INFO - ##### 17:20:49 INFO - Running pre-action listener: _resource_record_pre_action 17:20:49 INFO - Running main action method: create_virtualenv 17:20:49 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 17:20:49 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 17:20:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 17:20:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:20:49 INFO - Using real prefix '/usr' 17:20:49 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 17:20:49 INFO - Installing distribute.............................................................................................................................................................................................done. 17:20:51 INFO - Installing pip.................done. 17:20:51 INFO - Return code: 0 17:20:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 17:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:20:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 17:20:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:20:51 INFO - Using env: {'DISPLAY': ':0', 17:20:51 INFO - 'HOME': '/home/cltbld', 17:20:51 INFO - 'LANG': 'en_US.UTF-8', 17:20:51 INFO - 'LANGUAGE': 'en_US:en', 17:20:51 INFO - 'LOGNAME': 'cltbld', 17:20:51 INFO - 'MAIL': '/var/mail/cltbld', 17:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:51 INFO - 'MOZ_NO_REMOTE': '1', 17:20:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:51 INFO - 'NO_EM_RESTART': '1', 17:20:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:20:51 INFO - 'PWD': '/builds/slave/test-pgo', 17:20:51 INFO - 'SHELL': '/bin/bash', 17:20:51 INFO - 'SHLVL': '1', 17:20:51 INFO - 'TERM': 'linux', 17:20:51 INFO - 'TMOUT': '86400', 17:20:51 INFO - 'USER': 'cltbld', 17:20:51 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:51 INFO - '_': '/tools/buildbot/bin/python'} 17:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:52 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 17:20:52 INFO - Cleaning up... 17:20:52 INFO - Return code: 0 17:20:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 17:20: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')]} 17:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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')]} 17:20:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:20:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 17:20:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:20:52 INFO - Using env: {'DISPLAY': ':0', 17:20:52 INFO - 'HOME': '/home/cltbld', 17:20:52 INFO - 'LANG': 'en_US.UTF-8', 17:20:52 INFO - 'LANGUAGE': 'en_US:en', 17:20:52 INFO - 'LOGNAME': 'cltbld', 17:20:52 INFO - 'MAIL': '/var/mail/cltbld', 17:20:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:52 INFO - 'MOZ_NO_REMOTE': '1', 17:20:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:52 INFO - 'NO_EM_RESTART': '1', 17:20:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:20:52 INFO - 'PWD': '/builds/slave/test-pgo', 17:20:52 INFO - 'SHELL': '/bin/bash', 17:20:52 INFO - 'SHLVL': '1', 17:20:52 INFO - 'TERM': 'linux', 17:20:52 INFO - 'TMOUT': '86400', 17:20:52 INFO - 'USER': 'cltbld', 17:20:52 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:20:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:52 INFO - '_': '/tools/buildbot/bin/python'} 17:20:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:52 INFO - Downloading/unpacking psutil>=0.7.1 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20:53 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 17:20:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 17:20:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 17:20:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:54 INFO - Installing collected packages: psutil 17:20:54 INFO - Running setup.py install for psutil 17:20:54 INFO - building 'psutil._psutil_linux' extension 17:20:54 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 17:20:54 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 17:20:54 INFO - building 'psutil._psutil_posix' extension 17:20:54 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 17:20:54 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 17:20:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:54 INFO - Successfully installed psutil 17:20:54 INFO - Cleaning up... 17:20:54 INFO - Return code: 0 17:20:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 17:20: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')]} 17:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20: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')]} 17:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:20:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 17:20:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:20:54 INFO - Using env: {'DISPLAY': ':0', 17:20:54 INFO - 'HOME': '/home/cltbld', 17:20:54 INFO - 'LANG': 'en_US.UTF-8', 17:20:54 INFO - 'LANGUAGE': 'en_US:en', 17:20:54 INFO - 'LOGNAME': 'cltbld', 17:20:54 INFO - 'MAIL': '/var/mail/cltbld', 17:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:54 INFO - 'MOZ_NO_REMOTE': '1', 17:20:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:54 INFO - 'NO_EM_RESTART': '1', 17:20:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:20:54 INFO - 'PWD': '/builds/slave/test-pgo', 17:20:54 INFO - 'SHELL': '/bin/bash', 17:20:54 INFO - 'SHLVL': '1', 17:20:54 INFO - 'TERM': 'linux', 17:20:54 INFO - 'TMOUT': '86400', 17:20:54 INFO - 'USER': 'cltbld', 17:20:54 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:54 INFO - '_': '/tools/buildbot/bin/python'} 17:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:20:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 17:20:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:20:56 INFO - Installing collected packages: mozsystemmonitor 17:20:56 INFO - Running setup.py install for mozsystemmonitor 17:20:56 INFO - Successfully installed mozsystemmonitor 17:20:56 INFO - Cleaning up... 17:20:56 INFO - Return code: 0 17:20:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 17:20:56 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')]} 17:20:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:56 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')]} 17:20:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:20:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 17:20:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:20:56 INFO - Using env: {'DISPLAY': ':0', 17:20:56 INFO - 'HOME': '/home/cltbld', 17:20:56 INFO - 'LANG': 'en_US.UTF-8', 17:20:56 INFO - 'LANGUAGE': 'en_US:en', 17:20:56 INFO - 'LOGNAME': 'cltbld', 17:20:56 INFO - 'MAIL': '/var/mail/cltbld', 17:20:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:56 INFO - 'MOZ_NO_REMOTE': '1', 17:20:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:56 INFO - 'NO_EM_RESTART': '1', 17:20:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:20:56 INFO - 'PWD': '/builds/slave/test-pgo', 17:20:56 INFO - 'SHELL': '/bin/bash', 17:20:56 INFO - 'SHLVL': '1', 17:20:56 INFO - 'TERM': 'linux', 17:20:56 INFO - 'TMOUT': '86400', 17:20:56 INFO - 'USER': 'cltbld', 17:20:56 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:20:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:56 INFO - '_': '/tools/buildbot/bin/python'} 17:20:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:56 INFO - Downloading/unpacking blobuploader==1.2.4 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20:58 INFO - Downloading blobuploader-1.2.4.tar.gz 17:20:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 17:20:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:20:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:20:58 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 17:20:58 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 17:20:58 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 17:20:58 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 17:20:58 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 17:20:58 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 17:20:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 17:20:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 17:20:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:20:59 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 17:20:59 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 17:20:59 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 17:20:59 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 17:20:59 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 17:20:59 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 17:20:59 INFO - Downloading docopt-0.6.1.tar.gz 17:20:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 17:20:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 17:20:59 INFO - Installing collected packages: blobuploader, requests, docopt 17:20:59 INFO - Running setup.py install for blobuploader 17:20:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:20:59 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 17:20:59 INFO - Running setup.py install for requests 17:20:59 INFO - Running setup.py install for docopt 17:20:59 INFO - Successfully installed blobuploader requests docopt 17:20:59 INFO - Cleaning up... 17:20:59 INFO - Return code: 0 17:20:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:20:59 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')]} 17:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:59 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')]} 17:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:20:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 17:20:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:20:59 INFO - Using env: {'DISPLAY': ':0', 17:20:59 INFO - 'HOME': '/home/cltbld', 17:20:59 INFO - 'LANG': 'en_US.UTF-8', 17:20:59 INFO - 'LANGUAGE': 'en_US:en', 17:20:59 INFO - 'LOGNAME': 'cltbld', 17:20:59 INFO - 'MAIL': '/var/mail/cltbld', 17:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:59 INFO - 'MOZ_NO_REMOTE': '1', 17:20:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:20:59 INFO - 'NO_EM_RESTART': '1', 17:20:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:20:59 INFO - 'PWD': '/builds/slave/test-pgo', 17:20:59 INFO - 'SHELL': '/bin/bash', 17:20:59 INFO - 'SHLVL': '1', 17:20:59 INFO - 'TERM': 'linux', 17:20:59 INFO - 'TMOUT': '86400', 17:20:59 INFO - 'USER': 'cltbld', 17:20:59 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:59 INFO - '_': '/tools/buildbot/bin/python'} 17:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:21:00 INFO - Running setup.py (path:/tmp/pip-0DjWkN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:21:00 INFO - Running setup.py (path:/tmp/pip-6PxOqL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:21:00 INFO - Running setup.py (path:/tmp/pip-NdDBUV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:21:00 INFO - Running setup.py (path:/tmp/pip-LiQVjH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:21:00 INFO - Running setup.py (path:/tmp/pip-ipngQm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:21:00 INFO - Running setup.py (path:/tmp/pip-pbdw4O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:21:00 INFO - Running setup.py (path:/tmp/pip-A45NQy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:21:00 INFO - Running setup.py (path:/tmp/pip-dQ3Ht4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:21:00 INFO - Running setup.py (path:/tmp/pip-ebBt1B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:21:00 INFO - Running setup.py (path:/tmp/pip-s_OXS6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:21:00 INFO - Running setup.py (path:/tmp/pip-08EC5K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:21:00 INFO - Running setup.py (path:/tmp/pip-mV0tEw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:21:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:21:00 INFO - Running setup.py (path:/tmp/pip-9ec4ts-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:21:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:21:01 INFO - Running setup.py (path:/tmp/pip-tc2eKP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:21:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:21:01 INFO - Running setup.py (path:/tmp/pip-nYc9X5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:21:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:21:01 INFO - Running setup.py (path:/tmp/pip-9WEcHs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:21:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:21:01 INFO - Running setup.py (path:/tmp/pip-JWuhpf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:21:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:21:01 INFO - Running setup.py install for manifestparser 17:21:01 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 17:21:01 INFO - Running setup.py install for mozcrash 17:21:01 INFO - Running setup.py install for mozdebug 17:21:01 INFO - Running setup.py install for mozdevice 17:21:01 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 17:21:01 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 17:21:01 INFO - Running setup.py install for mozfile 17:21:01 INFO - Running setup.py install for mozhttpd 17:21:01 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 17:21:01 INFO - Running setup.py install for mozinfo 17:21:02 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Running setup.py install for mozInstall 17:21:02 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Running setup.py install for mozleak 17:21:02 INFO - Running setup.py install for mozlog 17:21:02 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Running setup.py install for moznetwork 17:21:02 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Running setup.py install for mozprocess 17:21:02 INFO - Running setup.py install for mozprofile 17:21:02 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Running setup.py install for mozrunner 17:21:02 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 17:21:02 INFO - Running setup.py install for mozscreenshot 17:21:02 INFO - Running setup.py install for moztest 17:21:02 INFO - Running setup.py install for mozversion 17:21:03 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 17:21:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:21:03 INFO - Cleaning up... 17:21:03 INFO - Return code: 0 17:21:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 17:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:21:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 17:21:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:21:03 INFO - Using env: {'DISPLAY': ':0', 17:21:03 INFO - 'HOME': '/home/cltbld', 17:21:03 INFO - 'LANG': 'en_US.UTF-8', 17:21:03 INFO - 'LANGUAGE': 'en_US:en', 17:21:03 INFO - 'LOGNAME': 'cltbld', 17:21:03 INFO - 'MAIL': '/var/mail/cltbld', 17:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:03 INFO - 'MOZ_NO_REMOTE': '1', 17:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:03 INFO - 'NO_EM_RESTART': '1', 17:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:21:03 INFO - 'PWD': '/builds/slave/test-pgo', 17:21:03 INFO - 'SHELL': '/bin/bash', 17:21:03 INFO - 'SHLVL': '1', 17:21:03 INFO - 'TERM': 'linux', 17:21:03 INFO - 'TMOUT': '86400', 17:21:03 INFO - 'USER': 'cltbld', 17:21:03 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:03 INFO - '_': '/tools/buildbot/bin/python'} 17:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:21:03 INFO - Running setup.py (path:/tmp/pip-LG0KZz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:21:03 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)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:21:03 INFO - Running setup.py (path:/tmp/pip-FVTQMS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:21:03 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)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:21:03 INFO - Running setup.py (path:/tmp/pip-yMrOrd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:21:03 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)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:21:03 INFO - Running setup.py (path:/tmp/pip-IGDamr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:21:03 INFO - Running setup.py (path:/tmp/pip-Yvdw3y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:21:03 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)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:21:03 INFO - Running setup.py (path:/tmp/pip-8r5T_P-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:21:03 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)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:21:03 INFO - Running setup.py (path:/tmp/pip-fp44Xb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:21:03 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)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:21:03 INFO - Running setup.py (path:/tmp/pip-fcOdqI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:21:03 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)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:21:03 INFO - Running setup.py (path:/tmp/pip-6u8GrU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:21:03 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)) 17:21:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:21:03 INFO - Running setup.py (path:/tmp/pip-XcXuL4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:21:04 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)) 17:21:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:21:04 INFO - Running setup.py (path:/tmp/pip-827CJq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:21:04 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)) 17:21:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:21:04 INFO - Running setup.py (path:/tmp/pip-22zWOH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:21:04 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)) 17:21:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:21:04 INFO - Running setup.py (path:/tmp/pip-lWZskb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:21:04 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)) 17:21:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:21:04 INFO - Running setup.py (path:/tmp/pip-TXwcSv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:21:04 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)) 17:21:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:21:04 INFO - Running setup.py (path:/tmp/pip-39ZWB3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:21:04 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)) 17:21:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:21:04 INFO - Running setup.py (path:/tmp/pip-Kzepjd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:21:04 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)) 17:21:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:21:04 INFO - Running setup.py (path:/tmp/pip-accICz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:21:04 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)) 17:21:04 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)) 17:21:04 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)) 17:21:04 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)) 17:21:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 17:21:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 17:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:21:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:21:06 INFO - Downloading blessings-1.5.1.tar.gz 17:21:06 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 17:21:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 17:21:06 INFO - Installing collected packages: blessings 17:21:06 INFO - Running setup.py install for blessings 17:21:06 INFO - Successfully installed blessings 17:21:06 INFO - Cleaning up... 17:21:06 INFO - Return code: 0 17:21:06 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 17:21:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:21:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:21:06 INFO - Reading from file tmpfile_stdout 17:21:06 INFO - Current package versions: 17:21:06 INFO - argparse == 1.2.1 17:21:06 INFO - blessings == 1.5.1 17:21:06 INFO - blobuploader == 1.2.4 17:21:06 INFO - docopt == 0.6.1 17:21:06 INFO - manifestparser == 1.1 17:21:06 INFO - mozInstall == 1.12 17:21:06 INFO - mozcrash == 0.16 17:21:06 INFO - mozdebug == 0.1 17:21:06 INFO - mozdevice == 0.47 17:21:06 INFO - mozfile == 1.2 17:21:06 INFO - mozhttpd == 0.7 17:21:06 INFO - mozinfo == 0.9 17:21:06 INFO - mozleak == 0.1 17:21:06 INFO - mozlog == 3.1 17:21:06 INFO - moznetwork == 0.27 17:21:06 INFO - mozprocess == 0.22 17:21:06 INFO - mozprofile == 0.28 17:21:06 INFO - mozrunner == 6.11 17:21:06 INFO - mozscreenshot == 0.1 17:21:06 INFO - mozsystemmonitor == 0.0 17:21:06 INFO - moztest == 0.7 17:21:06 INFO - mozversion == 1.4 17:21:06 INFO - psutil == 3.1.1 17:21:06 INFO - requests == 1.2.3 17:21:06 INFO - wsgiref == 0.1.2 17:21:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:21:06 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')]} 17:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:06 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')]} 17:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:21:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 17:21:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:21:06 INFO - Using env: {'DISPLAY': ':0', 17:21:06 INFO - 'HOME': '/home/cltbld', 17:21:06 INFO - 'LANG': 'en_US.UTF-8', 17:21:06 INFO - 'LANGUAGE': 'en_US:en', 17:21:06 INFO - 'LOGNAME': 'cltbld', 17:21:06 INFO - 'MAIL': '/var/mail/cltbld', 17:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:06 INFO - 'MOZ_NO_REMOTE': '1', 17:21:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:06 INFO - 'NO_EM_RESTART': '1', 17:21:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:21:06 INFO - 'PWD': '/builds/slave/test-pgo', 17:21:06 INFO - 'SHELL': '/bin/bash', 17:21:06 INFO - 'SHLVL': '1', 17:21:06 INFO - 'TERM': 'linux', 17:21:06 INFO - 'TMOUT': '86400', 17:21:06 INFO - 'USER': 'cltbld', 17:21:06 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:06 INFO - '_': '/tools/buildbot/bin/python'} 17:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 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)) 17:21:06 INFO - Cleaning up... 17:21:06 INFO - Return code: 0 17:21:06 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 17:21:06 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')]} 17:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:06 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')]} 17:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:21:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 17:21:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:21:06 INFO - Using env: {'DISPLAY': ':0', 17:21:06 INFO - 'HOME': '/home/cltbld', 17:21:06 INFO - 'LANG': 'en_US.UTF-8', 17:21:06 INFO - 'LANGUAGE': 'en_US:en', 17:21:06 INFO - 'LOGNAME': 'cltbld', 17:21:06 INFO - 'MAIL': '/var/mail/cltbld', 17:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:06 INFO - 'MOZ_NO_REMOTE': '1', 17:21:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:06 INFO - 'NO_EM_RESTART': '1', 17:21:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:21:06 INFO - 'PWD': '/builds/slave/test-pgo', 17:21:06 INFO - 'SHELL': '/bin/bash', 17:21:06 INFO - 'SHLVL': '1', 17:21:06 INFO - 'TERM': 'linux', 17:21:06 INFO - 'TMOUT': '86400', 17:21:06 INFO - 'USER': 'cltbld', 17:21:06 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:06 INFO - '_': '/tools/buildbot/bin/python'} 17:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:07 INFO - Downloading/unpacking jsonschema 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:21:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 17:21:08 INFO - Downloading/unpacking functools32 (from jsonschema) 17:21:08 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 17:21:08 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 17:21:08 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 17:21:08 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 17:21:08 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 17:21:08 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 17:21:08 INFO - Downloading functools32-3.2.3-2.tar.gz 17:21:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 17:21:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 17:21:09 INFO - warning: no files found matching '*.txt' 17:21:09 INFO - no previously-included directories found matching 'build' 17:21:09 INFO - no previously-included directories found matching 'dist' 17:21:09 INFO - no previously-included directories found matching '.git*' 17:21:09 INFO - Installing collected packages: jsonschema, functools32 17:21:09 INFO - Running setup.py install for functools32 17:21:09 INFO - warning: no files found matching '*.txt' 17:21:09 INFO - no previously-included directories found matching 'build' 17:21:09 INFO - no previously-included directories found matching 'dist' 17:21:09 INFO - no previously-included directories found matching '.git*' 17:21:09 INFO - Successfully installed jsonschema functools32 17:21:09 INFO - Cleaning up... 17:21:09 INFO - Return code: 0 17:21:09 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 17:21:09 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')]} 17:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:21:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:09 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')]} 17:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:21:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:21:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f25770eff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x120b630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, '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': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', '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 17:21:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 17:21:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:21:09 INFO - Using env: {'DISPLAY': ':0', 17:21:09 INFO - 'HOME': '/home/cltbld', 17:21:09 INFO - 'LANG': 'en_US.UTF-8', 17:21:09 INFO - 'LANGUAGE': 'en_US:en', 17:21:09 INFO - 'LOGNAME': 'cltbld', 17:21:09 INFO - 'MAIL': '/var/mail/cltbld', 17:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:09 INFO - 'MOZ_NO_REMOTE': '1', 17:21:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:09 INFO - 'NO_EM_RESTART': '1', 17:21:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:21:09 INFO - 'PWD': '/builds/slave/test-pgo', 17:21:09 INFO - 'SHELL': '/bin/bash', 17:21:09 INFO - 'SHLVL': '1', 17:21:09 INFO - 'TERM': 'linux', 17:21:09 INFO - 'TMOUT': '86400', 17:21:09 INFO - 'USER': 'cltbld', 17:21:09 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:09 INFO - '_': '/tools/buildbot/bin/python'} 17:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:21:09 INFO - Downloading/unpacking flake8 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21: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 17:21:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:21:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 17:21:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:21:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 17:21:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 17:21:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 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 17:21:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 17:21:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 17:21:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:21:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:21:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:21:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:21:11 INFO - no previously-included directories found matching 'docs/_build' 17:21:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:21:11 INFO - Running setup.py install for pep8 17:21:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:21:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:21:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:21:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:21:11 INFO - no previously-included directories found matching 'docs/_build' 17:21:11 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 17:21:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:21:11 INFO - Cleaning up... 17:21:12 INFO - Return code: 0 17:21:12 INFO - Running post-action listener: _resource_record_post_action 17:21:12 INFO - Running post-action listener: _start_resource_monitoring 17:21:12 INFO - Starting resource monitoring. 17:21:12 INFO - ##### 17:21:12 INFO - ##### Running install step. 17:21:12 INFO - ##### 17:21:12 INFO - Running pre-action listener: _resource_record_pre_action 17:21:12 INFO - Running main action method: install 17:21:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:21:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:21:12 INFO - Reading from file tmpfile_stdout 17:21:12 INFO - Detecting whether we're running mozinstall >=1.0... 17:21:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 17:21:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 17:21:12 INFO - Reading from file tmpfile_stdout 17:21:12 INFO - Output received: 17:21:12 INFO - Usage: mozinstall [options] installer 17:21:12 INFO - Options: 17:21:12 INFO - -h, --help show this help message and exit 17:21:12 INFO - -d DEST, --destination=DEST 17:21:12 INFO - Directory to install application into. [default: 17:21:12 INFO - "/builds/slave/test-pgo"] 17:21:12 INFO - --app=APP Application being installed. [default: firefox] 17:21:12 INFO - mkdir: /builds/slave/test-pgo/build/application 17:21:12 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'] 17:21:12 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 17:21:28 INFO - Reading from file tmpfile_stdout 17:21:28 INFO - Output received: 17:21:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 17:21:28 INFO - Running post-action listener: _resource_record_post_action 17:21:28 INFO - ##### 17:21:28 INFO - ##### Running run-tests step. 17:21:28 INFO - ##### 17:21:28 INFO - Running pre-action listener: _resource_record_pre_action 17:21:28 INFO - Running main action method: run_tests 17:21:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 17:21:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 17:21:28 INFO - Python 2.7.3 17:21:28 INFO - Return code: 0 17:21:28 INFO - grabbing minidump binary from tooltool 17:21:28 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')]} 17:21:28 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 0x129c840>, '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 0x12096b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1209b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 17:21:28 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 17:21:28 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 17:21:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:21:28 INFO - Return code: 0 17:21:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 17:21:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 17:21:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 17:21:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 17:21:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 17:21:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 17:21:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 17:21:29 INFO - Return code: 0 17:21:29 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 17:21:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName B2G-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 17:21:29 INFO - Using env: {'DISPLAY': ':0', 17:21:29 INFO - 'HOME': '/home/cltbld', 17:21:29 INFO - 'LANG': 'en_US.UTF-8', 17:21:29 INFO - 'LANGUAGE': 'en_US:en', 17:21:29 INFO - 'LOGNAME': 'cltbld', 17:21:29 INFO - 'MAIL': '/var/mail/cltbld', 17:21:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:21:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 17:21:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:21:29 INFO - 'MOZ_NO_REMOTE': '1', 17:21:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:21:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:21:29 INFO - 'NO_EM_RESTART': '1', 17:21:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:21:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:21:29 INFO - 'PWD': '/builds/slave/test-pgo', 17:21:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 17:21:29 INFO - 'SHELL': '/bin/bash', 17:21:29 INFO - 'SHLVL': '1', 17:21:29 INFO - 'TERM': 'linux', 17:21:29 INFO - 'TMOUT': '86400', 17:21:29 INFO - 'USER': 'cltbld', 17:21:29 INFO - 'XDG_SESSION_COOKIE': '01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303', 17:21:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:21:29 INFO - '_': '/tools/buildbot/bin/python'} 17:21:29 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 17:21:29 INFO - mozversion application_buildid: 20151229143009 17:21:29 INFO - mozversion application_changeset: 8ea3973329af0806c3621300e89ab996eb640c1a 17:21:29 INFO - mozversion application_display_name: Nightly 17:21:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:21:29 INFO - mozversion application_name: Firefox 17:21:29 INFO - mozversion application_remotingname: firefox 17:21:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/b2g-inbound 17:21:29 INFO - mozversion application_vendor: Mozilla 17:21:29 INFO - mozversion application_version: 46.0a1 17:21:29 INFO - mozversion platform_buildid: 20151229143009 17:21:29 INFO - mozversion platform_changeset: 8ea3973329af0806c3621300e89ab996eb640c1a 17:21:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/b2g-inbound 17:21:29 INFO - mozversion platform_version: 46.0a1 17:21:29 INFO - using testdate: 1451438489 17:21:29 INFO - actual date: 1451438489 17:21:29 INFO - starting webserver on 'localhost:40892' 17:21:29 INFO - SUITE-START | Running 3 tests 17:21:29 INFO - TEST-START | dromaeo_css 17:21:29 INFO - operating with platform_type : linux_ 17:21:29 INFO - Initialising browser for dromaeo_css test... 17:21:29 INFO - TEST-INFO | started process 6560 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpx9KoIB/profile http://localhost:40892/getInfo.html) 17:21:33 INFO - PROCESS | 6560 | __metrics Screen width/height:1600/1200 17:21:33 INFO - PROCESS | 6560 | colorDepth:24 17:21:33 INFO - PROCESS | 6560 | Browser inner width/height: 1024/697 17:21:33 INFO - PROCESS | 6560 | __metrics 17:21:34 INFO - PROCESS | 6560 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:21:34 INFO - PROCESS | 6560 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:21:35 INFO - TEST-INFO | 6560: exit 0 17:21:35 INFO - Browser initialized. 17:21:35 INFO - Running cycle 1/1 for dromaeo_css test... 17:21:35 INFO - TEST-INFO | started process 6655 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpx9KoIB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:46 INFO - PROCESS | 6655 | RSS: Main: 148328448 17:21:46 INFO - PROCESS | 6655 | 17:22:38 INFO - PROCESS | 6655 | 1451438558285 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] 17:24:47 INFO - PROCESS | 6655 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40892/tests/dromaeo/cssquery-ext.html) 17:24:48 INFO - PROCESS | 6655 | RSS: Main: 244215808 17:24:48 INFO - PROCESS | 6655 | 17:27:50 INFO - PROCESS | 6655 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-ext.html (next: http://localhost:40892/tests/dromaeo/cssquery-jquery.html) 17:27:51 INFO - PROCESS | 6655 | RSS: Main: 238362624 17:27:51 INFO - PROCESS | 6655 | 17:30:52 INFO - PROCESS | 6655 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40892/tests/dromaeo/cssquery-mootools.html) 17:30:53 INFO - PROCESS | 6655 | RSS: Main: 230850560 17:30:53 INFO - PROCESS | 6655 | 17:33:55 INFO - PROCESS | 6655 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40892/tests/dromaeo/cssquery-prototype.html) 17:33:55 INFO - PROCESS | 6655 | RSS: Main: 214806528 17:33:55 INFO - PROCESS | 6655 | 17:36:58 INFO - PROCESS | 6655 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40892/tests/dromaeo/cssquery-yui.html) 17:36:59 INFO - PROCESS | 6655 | RSS: Main: 221892608 17:36:59 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | Cycle 1(1): loaded http://localhost:40892/tests/dromaeo/cssquery-yui.html (next: http://localhost:40892/tests/dromaeo/cssquery-dojo.html) 17:40:01 INFO - PROCESS | 6655 | RSS: Main: 285102080 17:40:01 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | __start_tp_report 17:40:01 INFO - PROCESS | 6655 | _x_x_mozilla_page_load 17:40:01 INFO - PROCESS | 6655 | _x_x_mozilla_page_load_details 17:40:01 INFO - PROCESS | 6655 | |i|pagename|runs| 17:40:01 INFO - PROCESS | 6655 | |0;dojo.html;3144.276816332244;3188.7767856250043;3230.7899986500875;3237.223066464048;3258.6578409267026;5160.096983027974;5229.372475302965;5232.502912223342;5285.339332583427;5308.708021058845;5253.86865328366;5318.282031925689;5319.547838433728;5324.201369794523;5337.733113344337;4660.953390466103;4746.403862425966;4768.546988036131;4878.243872199815;4948.430930443009;4710.481846996828;4878.878028049292;4901.754912254373;4939.604831613462;4947.529984651453;4779.450363208241;4808.831690890819;4848.0788650156355;4873.829415970459;4878.585320247783;4854.878628034292;4856.05306965142;4861.781219845116;4886.804527818883;4903.4851340609075;4715.269133284346;4825.131476334259;4832.613390928753;4870.269459581055;4888.755562221874;4996.200607902754;5006.4492905780335;5086.18621020635;5093.439721630618;5128.512791284822;5695.259616249861;5697.145428185805;5707.058335374658;5709.457601527848;5713.914291285592;5528.6406383585345;5692.203091567184;5740.995325817965;5742.483176514092;5748.42515748426;5263.421023687391;5321.308229930084;5342.305500284938;5359.946400536022;5362.865928351784;5776.537876969833;5788.971055144697;5790.42095790426;5793.362730099686;5798.884022319615;5465.945340546623;5562.944370556243;5571.637843540157;5577.9163312550345;5587.078132108157;5567.972160139173;5619.971900140473;5620.97189514058;5744.684042377709;5750.1949727038245;5597.356304025007;5601.495865372136;5601.719914004365;5607.775688972477;5610.298712660918;5565.3599836019375;5612.242347283146;5614.2701448811395;5614.9157762633595;5618.325800903917;4332.176886391576;4369.798155993023;4414.867553973386;4418.889527761768;4419.668524860648;3899.1421887184774;3947.111899822517;3955.545112312121;3976.920461590752;3977.960220397817;5339.519443250126;5342.2787923630585;5342.358916929993;5347.064263753828;5365.3293338332705;5738.684372359477;5741.138829175574;5743.253377060955;5744.138379243063;5746.597738158289;5167.069927413102;5216.37403511581;5265.341832270966;5271.4728527147745;5370.785168593291;4915.21356582945;4947;4948.653594248368;4959.181735013754;4974.90050198994;5893.182348241647;5913.408659134138;5913.822585322448;5929.52563794904;5966.254218222723;5613.438656134354;5613.438656134354;5687.459691329317;5712.257406537124;5721.370649228665;5899;5900.055991041413;5900.144479050427;5945.10823376497;5960.940390596039;5601.383847776659;5606.719664016864;5608.355039170548;5610.354809196994;5629.718514074362;5562.860928476821;5625.803096891589;5643.266375371176;5720.8283751487525;5724.169995350734;5529.972350138223;5574.136008918624;5575.637583557056;5607.859803504945;5657.60396772222;5598.3282006159525;5619.044762390486;5640.60515763909;5653.604247702786;5658.603897727119;5414.485623865727;5505.752241149078;5528.308961379827;5555.2222688822785;5566.693831839288;5483.8080667176455;5570.637908535932;5669.716514174357;5681.659100453986;5714.885702286014;5377.677339359651;5384.219288203267;5384.569234461155;5390.51485366319;5443.210734443404 17:40:01 INFO - PROCESS | 6655 | |1;ext.html;520.8099043848996;530.1782237531825;533.0644718518998;556.1213283012837;557.4007941462927;27522.86238568807;28104.718952810465;28237.435251294988;35187.64812351876;35566.8221658892;37649;38224;38269.617303826955;38283.617163828356;38284;8316.04365497968;8467.449615774985;8471.06818249992;8474;8475.491470511759;7616.885746713804;7774.766756997299;7787.883181752279;7790.922090779076;8273.214044665778;7424.628768561551;10058.145057670106;10282.177425805956;10293.48532573372;10304.536295866666;9926.25553083518;10011.849822252672;10016.54925528353;10023.298369114165;10032.448215348226;7576.128745194318;7767.805804854867;9777.41335519871;9931.65239216624;9936.652217172365;38515.229695405935;38864.22271554553;39142.21715565701;39218.21563568741;39228.803855980535;3235.7896736712037;3262.8368581570826;3344.0803778960735;3462.290230502741;3739.196072844324;26686.46627067448;26688.19935401941;26823;26865.73134268652;27732.584011239847;3834.559025712051;3851.114243723959;3865.8016015035146;3927.2538217738825;3983.143624120828;214439;215197;215261;215599;215809;55071.173932391044;55097.44902551003;55212.17181742277;55553.72223138859;55674.44325556693;57267.71366143226;57520.137197942066;57588.71205643945;57653;57666.7116664414;13407.93296033533;13478.932605337106;13581.185128892299;13581.45674173022;13591.116577422436;11157.051650609626;11170.497327620276;11171.162162837658;11171.888281117084;11184.496697648625;18616.363271872295;19423.44867341515;19493.512662183275;19501.90249048774;19535.49596625399;7479;7592.354649854714;7709.38324934004;7742.14836367999;7754.806129846799;990.1929927109383;1047.675220681591;1051.6109039655375;1078.3152698036804;1094.2067001423904;28559.85720071428;28608.427831443256;28927.85536072306;29199.41601167965;29626.851865740533;996.9102780749622;1022.544967489475;1041.7551875309296;1086.163653986426;1120.697411698853;13779.793303100461;13995.23026233567;14056.648583785487;14095;14172.94492298611;306712;308942;311967;312290;312324;38700.22599547993;50129.248061278384;51335.48664513307;51359.74320128375;51589;1327.4557431452865;1335.833020872391;1340.0887396570427;1365.1740696878444;1434.5624584501618;25585.744142558335;26187.476250475265;27422;28921.034479311482;29200.707992919797;45480.54519454763;46289;46310.768446157555;46340.30489542727;46379.53620463752;191288;191639;191682;191880;191994;30532.236694082825;30630.08109756711;30650.233744156576;30824.229394265323;31007.68992310048;41991.3701294487;42606.786966064974;43143.56856431521;43265.35101973536;43266.35100473558;34522.482162767075;35501.28997420089;35890.820545897106;36805;37084;35857.64142358543;36039.27921441504;36237.818810905774;36744.63255367412;37005.81497092497;32138.517922230716;33047.17382065371;33096;33114;33165.33669326648;29402;31648;31799.410029498897;31858.362832743678;32110.839445802623;23900;23953.56278623288;24019.399515012265;24599.87700061488;24802.50394992126 17:40:01 INFO - PROCESS | 6655 | |2;jquery.html;350.9631488693687;359.3944204016231;360.6375592529507;362.2121884900342;365.30591875436676;13315.733685326288;13646.270745850845;15976.520704378872;16741.58146046348;17700.964389095243;16048.518544443656;16905.323787048503;17246.05146716933;17249.91375043126;17339.653206935884;5278.683279003262;5403.756830942617;5410.2966614340085;5413.566914646819;5414.377346605132;4036.1120553478336;4148.771817550033;5362.35651721794;5409.080456322435;5783.768649254046;5954.880902381971;5992.161097446361;6011.248593925759;6020.12708157317;6024.126501657257;5267.683938963655;5389.461053894618;5430.212619170237;5431.809886653988;5966.313873904492;5176.715280659562;5187.636865419385;5196.818111366084;5199.818006369796;5210.583153347761;15835.524934251991;20053.79946200534;20388.388348349574;20453.897730511402;20569.382918512467;2049.5388537579074;2072.554400803819;2123.77700341465;2153.558520503293;2192.9780702192934;9680;9698.563564639608;9857.457839818808;9894.90105098947;12522.624321270376;1789.677857985562;1841.1438681013392;1870.728744332078;1881.7930027696943;1884.5759704066613;57984.710076449766;60271.69864150695;60275;60764.69617651883;61111.38888611146;9504.002079781523;9558.95220523893;9571.425714457155;9623.56693948774;9642.95178524117;12500.062495312888;12515.561955331701;12522.186057906207;12537.373131343578;12562.120651554304;7257.201707812136;7333.853322933512;7437.550804645813;7491.8501629967095;7513.39892808585;7280.745173918887;7317.195108538057;7533.209013053551;7596.2023987481625;7641.847163056707;4448.733076015449;4531.048479819275;4542.500324964251;4555.06621142665;4555.407796986437;1726.5769886377918;1768.3810666266704;1797.766290382242;1813.3381315819895;1874.004185542393;915.8992510823804;972.6060945317158;982.5873133283895;986.4426598971559;1050.7653507129246;16746.6650666986;17061;17104.828951710573;17393.416376280773;17570.472885813448;900.9459432434074;926.4765407544716;951.7573018880141;952.2857856607544;1018.5467466977273;8879.28965682744;10023.749406264902;10082.19342452602;10083.697489075339;10102.646407375854;386356;396023;555851;563060;564985;71270.64364678247;76380.23619763843;82301.41588672892;84195.57902210573;85084;1123.230587047875;1149.6608500492375;1155.6821873984554;1157.9015783658315;1157.9826302605468;15683.215839208222;15940.282687279332;16072;16090.517284481484;16209.108499032667;34423.82788086044;35087;35131.64868351284;36143.63856361508;36380;47682.80792980203;47874.76062619664;48790;49996.75034121365;50401.49598503968;13745.587632371045;14689.41242350294;15036.548903532912;15424.768628470358;15478.767818482507;13332.866671333162;18217.180226889555;18492.907535462236;19170.04149792477;19215.80784192196;9676.806463870824;10030.448325342177;10037.49812509357;15340.616484587974;15515.301811418287;9907.157891579296;10161.898381016392;10230.539625716712;10283.588656453658;10496.632617858339;10076.395416275047;10387.584496620051;10405.739856503647;10422.322549034288;10527.684169474927;14392.424303027761;14574.854251457773;15013.849861501245;15046.774298385313;15079.095254284777;15220.086794792347;15262.923685381502;15304.311305991034;15649.92175039163;15833.287502062205 17:40:01 INFO - PROCESS | 6655 | |3;mootools.html;1334.372844762961;1467.7431449496341;1481.1779462398367;1616.8302328255534;1775.9112044397784;6662.433693136083;7056.717731290749;7156.892646610305;7207.207207207203;7214.314640109181;7061.611611361386;7232.13214414271;7247.601381923992;7250.818729531751;7260.564366138022;2222.277759728087;2238.764929682376;2251.2908433843286;2252.583272094668;2257.559775843711;2255.4812393149587;2269.909203631853;2322.1872344679396;2322.5354929014266;2322.8025617822505;2230.542738738563;2232.3079845248026;2259.1189436119926;2264.8188144948363;2303.9078436862505;2220.777922207766;2246.921357752479;2254.8534345267503;2268.6029944759603;2272.6931864198286;2246.696695946043;2259.197984715419;2259.988700056489;2260.9660855087186;2263.864168149916;7177.923311503264;7238.059052323217;7244.529105608171;7244.71021159153;7251.5286506376915;4495.842645507424;4536.77316134192;4549.6360291176625;4585.541445855388;4596.701214421052;2764.2536515141;2956.305268261957;2967.540031295131;2979.746721528673;2995.1014695591452;932.2262522106636;942.3780304998668;959.1271942532331;975.3075316525204;981.0336818234034;9981.251406144569;9995.100440960348;10001.749956251151;10013.399196048187;10015.89984100157;4085.816138273785;4138.875833724993;4148.377743338523;4167.874963751093;4210.578942105765;8644.049154592989;8646.567671616394;8675.305975521944;8689.869651955225;8720.084391138838;4611.538846115362;4619.930701039487;4622.884427889329;4626;4636.466806317317;3683.2633473305445;3810.9809450952566;3989.2819292527624;4259.488861336659;4265.7440553566885;3506.7895926243996;3518.806465644414;3544.486049522805;3545.2023294758474;3573.088862340139;1119.367557330106;1141.366541569437;1153.901918336934;1159.5245949160803;1169.3626973299401;1337.4382759241141;1345.9259740714356;1357.4434481862388;1373.7733274009677;1375.6835927736477;6750.763723269662;6951.304869513109;7162.319579639926;7176.677049532679;7179.820504487429;1120.9439528023565;1137.5279259107542;1138.573035111833;1146.7075895646724;1168.567629976914;5562;5605.7197140142825;5607.4112218217315;5612.214289999403;5613.887722245532;8469.872951905727;8518.48889066658;8681.522516261666;8792.780180495538;8866.113388661212;8808.51553164582;8858.468491890508;8886.422382545114;8887.066857979948;8939.865902011476;6711.261761206263;6769.323067693192;6786.69459874297;7041.366277035091;7130.714771409189;6825.522213445011;6883.483738719716;6885.586864788129;6886.139232595926;6890.379865812101;7652.846943060997;7794.727184548514;7806.687732490636;7868.25251601097;7914.683412663429;7735;8249.29880960125;8374.162583741578;8381;8394.58027098655;5965.28416590012;6292.9370706294185;6541.542092053511;6626.237982632059;6711.0940023096255;6874.278200788845;6900;6940.583564986117;6946.235914049349;6951.860962780817;6410.679466026587;6554.541182117206;6556.508261880283;6568.343165683394;6648.6343251119715;6549.410553050248;6575.342465753386;6581.835454113685;6607.405333520006;6643.136392268974;6694.698738556874;6713.261541230559;6713.932860671331;6720.193576770819;6741.86516269662;6127.969360153171;6213.9067913982235;6228.0969259456215;6228.532860035425;6261.74953001893;6244.157038799885;6268.216472940882;6287.119803227643;6288.339724329063;6324.778632747832 17:40:01 INFO - PROCESS | 6655 | |4;prototype.html;340.4875662128497;343.8435511842112;349.3153419298175;350.4847873625769;353.30399113745926;5961.105834124878;6026;6040.3053648830455;6051.122587224855;6073.817785466443;5947.01874190758;5952.255968003999;5960.6423614583155;5961.910571341434;5977.940220597793;4431.800568974404;4633.443986721598;4634.165850146972;4640.164770341337;4646.628269738413;4571.817127314904;4572.451305843303;4579.908401831962;4580.748058856761;4582;4501.842435514757;4516.570925762064;4521.864344069683;4526.97736511317;4528.04910968697;4369.934450983238;4441.222786012435;4442.133783912139;4449.777511124463;4539.4098767160385;3913.099987002977;3963.8612648557164;3973.284808734427;3976.5824588418377;3977.5226972763453;5605.243292155547;5606.747696353674;5612.214289999403;5618.185363122325;5657.575681823839;4975.427825800043;5065.746712664389;5090.38915330159;5294.364676238837;5298.258243845826;6003.699815009232;6156.5998210116195;6264.966280563702;6274.87450250997;6293.748250069992;4338.3058710606465;4378.387025816388;4381.07997320563;4382.342648602703;4382.737035777832;6468.514861385415;6489.643069631168;6522.706478208492;6593.571417857824;6599.736010559572;4494.5505449454795;4502.932456013163;4511.255642818898;4511.88720281989;4513.277875539898;4551.112533056023;4556.567126123013;4556.749378784199;4558.452985641744;4561.566651168134;5265.052290587731;5265.473452654704;5268.288781014514;5278.9472105278455;5284.894302113936;6142.3550527194;6294.244690637152;6363;6363.395477429637;6374.330695277022;1588.602849287678;1602.5512856400226;1603.6952978934198;1603.885299716709;1605.7992750906137;1064.2550214849637;1079.8488211650217;1083.7507373304184;1102.0522350778485;1103.6082190822303;5974;5977.521798256129;5982.790602328898;5986.5210783137245;5989.730462129215;6508.088867558546;6529.053287273318;6540;6552.344765523505;6560.278369379364;5978.282606087298;5978.342382337939;5986.042233242661;5990.460858522667;5990.760369585168;3434.862605495802;3518.5777706675362;3522.682958533693;3526.2242306692488;3530.3645343837843;6718.260991290954;6839.0425340452375;6839.760608378683;6840.213375461786;6849.04113424121;6433.935660643428;6762.289959554274;6765.52641315113;6768;6771.93228067713;6244.782267457802;6742.42689371409;6810.7059658665685;6822.181338239443;6825;6530.869382612416;6549.639769812706;6550.082988381726;6557.278699343164;6562.245341785756;6484.902726459201;6505.967470162619;6509.251436084911;6516.771912983023;6523.902141467976;6683.7660681875905;6807.455403567604;6809.795706128824;6811.148606424197;6824.658767061726;4441.844535441244;4455.287154055336;4458.264386376211;4460.330950357472;4468.754218517883;6570.704318305783;6575.145231119924;6576.112224849584;6578.24350199714;6580.144581204413;6263.96868015657;6363.331850155667;6410.134631824644;6410.5192110592825;6414.647194404352;6310.558260921875;6384.489240860627;6385.96807015962;6387.808365749035;6391.073294372383;6284.120223168668;6286.088517164894;6287.622742635456;6292.433680968735;6294.402031807154;6309.4952403806665;6392.169018027626;6430.710618022106;6442.2591401989375;6448.580842245239;6220.284667263141;6221.502279817694;6226.688665566794;6228.813135605939;6234.812955611339 17:40:01 INFO - PROCESS | 6655 | |5;yui.html;749.1272667342546;781.73811773056;813.0284310249252;865.1521508921253;874.6370256343617;13439.865601343983;13599.32003399831;13725.107867988574;13730.450781968722;13739.969502287318;12799.296038717866;12910.806337904938;12911;12921.16012459192;12926.159799613019;664.2460806984084;672.6199697171107;780.8360244348679;786.9370450363986;805.4120492040814;808.8261023879865;816.0493025625169;816.2205094135116;816.5019338203691;818.0510607695074;839.1063517354029;844.8352571248578;844.9662013519452;845.3068483843251;847.2247893177735;685.9725610975555;695.2005194026859;831.1024093978489;844.4131328726537;844.890164278646;622.3341025103141;624.7594676049708;624.8594066335083;627.6987046217841;630.338144947803;12884.033697472632;13011.804822927663;13029.54396596124;13037.282949437777;13055.543055992996;11406.942965285203;11626.593069242535;11809.881901180965;11908.34504102274;12358.62924112278;10185.236107291983;10294.279400441972;10357.171426285879;10516.631917882838;10608.575656973711;3559.661832125944;3617.706835869748;3643.052806270389;3663.5603727552866;3737.1591391936868;45130.548694513294;45561.54438455606;45948.770256148506;46136.53863461356;46247.768761156316;10316.43259620713;10358.482075896174;10414.114800242065;10479.790404192026;10638.946805265925;18636;18640.81359186391;18654.34709785178;18657.253709851455;18658.067096645114;7495.138059123271;7513.248675132443;7623.885641715378;7634.236576342432;7762.844743105106;4278.382181003926;4356.358709728868;4408.735475871458;4418.226810308183;4425.380446737459;2953.0845437913886;2984.388200418909;2988.4770165221;2990.4617168909804;2992.7605791536625;526.4603781124379;528.1127705454873;534.6765207049757;539.8947205294929;546.7758219130217;537.2746791830997;563.9041362968305;575.8675504633958;576.881739243454;586.7916889504164;6951.131108611424;7027.964860175666;7236.240194779578;7285.599292038883;7362.77911662651;560.8065217499956;567.6764244380663;574.9367569567344;587.9970600147058;600.5075837813039;3373.7301015919156;3504.3867323218333;3518.0852978225844;3582.4089025311055;3591.1919818040733;53281.46718532765;53521.73239133779;53814;53831.46168538343;54332.45667543353;48198.27702584464;48495.39293767086;48816.51183488191;49586.50413495819;50294.74852625786;43757.78121109374;44122.77938610286;44674.77662611666;44848.999999999345;45339.773301133944;6996.405305549085;7011;7055.576665400093;7163.892541611985;7265.418766498773;33842.15394615056;34372.82813585916;34462.310753785285;34820;35215.29569408649;37222.62777372191;42728;42989.570104299804;43177.56822431736;43216;9499.145076943109;9575.808483830146;9580.13778759915;9671.661491847752;9899.455529945923;10132.290739648153;10403.375797452178;10556.313839600401;10712.678619641423;10719.14246860265;9736.123748862636;10213.438260895722;10252.692419227435;10295.94852025735;10352.689419317432;9479.47862867549;9819.754506137404;9930.751731206778;10156.492175391053;10179.694609161737;8040.316573091352;10053.949730251303;10602.151827853908;10646.361218326616;11004.614838480615;9261.53692315395;9316.580753865957;9547.379420337656;9642.469664168539;9855.35940163887;8767.430117042371;8887.600057997277;9252.121048500381;9343.672971445967;9577.138057574852 17:40:01 INFO - PROCESS | 6655 | __end_tp_report 17:40:01 INFO - PROCESS | 6655 | __start_cc_report 17:40:01 INFO - PROCESS | 6655 | _x_x_mozilla_cycle_collect,855 17:40:01 INFO - PROCESS | 6655 | __end_cc_report 17:40:01 INFO - PROCESS | 6655 | __startTimestamp1451439601948__endTimestamp 17:40:01 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | ------- Summary: start ------- 17:40:01 INFO - PROCESS | 6655 | Number of tests: 6 17:40:01 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | [#0] dojo.html Cycles:165 Average:5277.45 Median:5483.81 stddev:577.04 (10.5%) stddev-sans-first:554.02 17:40:01 INFO - PROCESS | 6655 | Values: 3144.3 3188.8 3230.8 3237.2 3258.7 5160.1 5229.4 5232.5 5285.3 5308.7 5253.9 5318.3 5319.5 5324.2 5337.7 4661.0 4746.4 4768.5 4878.2 4948.4 4710.5 4878.9 4901.8 4939.6 4947.5 4779.5 4808.8 4848.1 4873.8 4878.6 4854.9 4856.1 4861.8 4886.8 4903.5 4715.3 4825.1 4832.6 4870.3 4888.8 4996.2 5006.4 5086.2 5093.4 5128.5 5695.3 5697.1 5707.1 5709.5 5713.9 5528.6 5692.2 5741.0 5742.5 5748.4 5263.4 5321.3 5342.3 5359.9 5362.9 5776.5 5789.0 5790.4 5793.4 5798.9 5465.9 5562.9 5571.6 5577.9 5587.1 5568.0 5620.0 5621.0 5744.7 5750.2 5597.4 5601.5 5601.7 5607.8 5610.3 5565.4 5612.2 5614.3 5614.9 5618.3 4332.2 4369.8 4414.9 4418.9 4419.7 3899.1 3947.1 3955.5 3976.9 3978.0 5339.5 5342.3 5342.4 5347.1 5365.3 5738.7 5741.1 5743.3 5744.1 5746.6 5167.1 5216.4 5265.3 5271.5 5370.8 4915.2 4947.0 4948.7 4959.2 4974.9 5893.2 5913.4 5913.8 5929.5 5966.3 5613.4 5613.4 5687.5 5712.3 5721.4 5899.0 5900.1 5900.1 5945.1 5960.9 5601.4 5606.7 5608.4 5610.4 5629.7 5562.9 5625.8 5643.3 5720.8 5724.2 5530.0 5574.1 5575.6 5607.9 5657.6 5598.3 5619.0 5640.6 5653.6 5658.6 5414.5 5505.8 5528.3 5555.2 5566.7 5483.8 5570.6 5669.7 5681.7 5714.9 5377.7 5384.2 5384.6 5390.5 5443.2 17:40:01 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | [#1] ext.html Cycles:180 Average:40914.60 Median:3249.31 stddev:63816.15 (1964.0%) stddev-sans-first:63923.09 17:40:01 INFO - PROCESS | 6655 | Values: 520.8 530.2 533.1 556.1 557.4 27522.9 28104.7 28237.4 35187.6 35566.8 37649.0 38224.0 38269.6 38283.6 38284.0 8316.0 8467.4 8471.1 8474.0 8475.5 7616.9 7774.8 7787.9 7790.9 8273.2 7424.6 10058.1 10282.2 10293.5 10304.5 9926.3 10011.8 10016.5 10023.3 10032.4 7576.1 7767.8 9777.4 9931.7 9936.7 38515.2 38864.2 39142.2 39218.2 39228.8 3235.8 3262.8 3344.1 3462.3 3739.2 26686.5 26688.2 26823.0 26865.7 27732.6 3834.6 3851.1 3865.8 3927.3 3983.1 214439.0 215197.0 215261.0 215599.0 215809.0 55071.2 55097.4 55212.2 55553.7 55674.4 57267.7 57520.1 57588.7 57653.0 57666.7 13407.9 13478.9 13581.2 13581.5 13591.1 11157.1 11170.5 11171.2 11171.9 11184.5 18616.4 19423.4 19493.5 19501.9 19535.5 7479.0 7592.4 7709.4 7742.1 7754.8 990.2 1047.7 1051.6 1078.3 1094.2 28559.9 28608.4 28927.9 29199.4 29626.9 996.9 1022.5 1041.8 1086.2 1120.7 13779.8 13995.2 14056.6 14095.0 14172.9 306712.0 308942.0 311967.0 312290.0 312324.0 38700.2 50129.2 51335.5 51359.7 51589.0 1327.5 1335.8 1340.1 1365.2 1434.6 25585.7 26187.5 27422.0 28921.0 29200.7 45480.5 46289.0 46310.8 46340.3 46379.5 191288.0 191639.0 191682.0 191880.0 191994.0 30532.2 30630.1 30650.2 30824.2 31007.7 41991.4 42606.8 43143.6 43265.4 43266.4 34522.5 35501.3 35890.8 36805.0 37084.0 35857.6 36039.3 36237.8 36744.6 37005.8 32138.5 33047.2 33096.0 33114.0 33165.3 29402.0 31648.0 31799.4 31858.4 32110.8 23900.0 23953.6 24019.4 24599.9 24802.5 17:40:01 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | [#2] jquery.html Cycles:180 Average:27668.96 Median:17387.40 stddev:81867.98 (470.8%) stddev-sans-first:82071.94 17:40:01 INFO - PROCESS | 6655 | Values: 351.0 359.4 360.6 362.2 365.3 13315.7 13646.3 15976.5 16741.6 17701.0 16048.5 16905.3 17246.1 17249.9 17339.7 5278.7 5403.8 5410.3 5413.6 5414.4 4036.1 4148.8 5362.4 5409.1 5783.8 5954.9 5992.2 6011.2 6020.1 6024.1 5267.7 5389.5 5430.2 5431.8 5966.3 5176.7 5187.6 5196.8 5199.8 5210.6 15835.5 20053.8 20388.4 20453.9 20569.4 2049.5 2072.6 2123.8 2153.6 2193.0 9680.0 9698.6 9857.5 9894.9 12522.6 1789.7 1841.1 1870.7 1881.8 1884.6 57984.7 60271.7 60275.0 60764.7 61111.4 9504.0 9559.0 9571.4 9623.6 9643.0 12500.1 12515.6 12522.2 12537.4 12562.1 7257.2 7333.9 7437.6 7491.9 7513.4 7280.7 7317.2 7533.2 7596.2 7641.8 4448.7 4531.0 4542.5 4555.1 4555.4 1726.6 1768.4 1797.8 1813.3 1874.0 915.9 972.6 982.6 986.4 1050.8 16746.7 17061.0 17104.8 17393.4 17570.5 900.9 926.5 951.8 952.3 1018.5 8879.3 10023.7 10082.2 10083.7 10102.6 386356.0 396023.0 555851.0 563060.0 564985.0 71270.6 76380.2 82301.4 84195.6 85084.0 1123.2 1149.7 1155.7 1157.9 1158.0 15683.2 15940.3 16072.0 16090.5 16209.1 34423.8 35087.0 35131.6 36143.6 36380.0 47682.8 47874.8 48790.0 49996.8 50401.5 13745.6 14689.4 15036.5 15424.8 15478.8 13332.9 18217.2 18492.9 19170.0 19215.8 9676.8 10030.4 10037.5 15340.6 15515.3 9907.2 10161.9 10230.5 10283.6 10496.6 10076.4 10387.6 10405.7 10422.3 10527.7 14392.4 14574.9 15013.8 15046.8 15079.1 15220.1 15262.9 15304.3 15649.9 15833.3 17:40:01 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | [#3] mootools.html Cycles:180 Average:5197.62 Median:6277.67 stddev:2610.47 (41.6%) stddev-sans-first:2601.64 17:40:01 INFO - PROCESS | 6655 | Values: 1334.4 1467.7 1481.2 1616.8 1775.9 6662.4 7056.7 7156.9 7207.2 7214.3 7061.6 7232.1 7247.6 7250.8 7260.6 2222.3 2238.8 2251.3 2252.6 2257.6 2255.5 2269.9 2322.2 2322.5 2322.8 2230.5 2232.3 2259.1 2264.8 2303.9 2220.8 2246.9 2254.9 2268.6 2272.7 2246.7 2259.2 2260.0 2261.0 2263.9 7177.9 7238.1 7244.5 7244.7 7251.5 4495.8 4536.8 4549.6 4585.5 4596.7 2764.3 2956.3 2967.5 2979.7 2995.1 932.2 942.4 959.1 975.3 981.0 9981.3 9995.1 10001.7 10013.4 10015.9 4085.8 4138.9 4148.4 4167.9 4210.6 8644.0 8646.6 8675.3 8689.9 8720.1 4611.5 4619.9 4622.9 4626.0 4636.5 3683.3 3811.0 3989.3 4259.5 4265.7 3506.8 3518.8 3544.5 3545.2 3573.1 1119.4 1141.4 1153.9 1159.5 1169.4 1337.4 1345.9 1357.4 1373.8 1375.7 6750.8 6951.3 7162.3 7176.7 7179.8 1120.9 1137.5 1138.6 1146.7 1168.6 5562.0 5605.7 5607.4 5612.2 5613.9 8469.9 8518.5 8681.5 8792.8 8866.1 8808.5 8858.5 8886.4 8887.1 8939.9 6711.3 6769.3 6786.7 7041.4 7130.7 6825.5 6883.5 6885.6 6886.1 6890.4 7652.8 7794.7 7806.7 7868.3 7914.7 7735.0 8249.3 8374.2 8381.0 8394.6 5965.3 6292.9 6541.5 6626.2 6711.1 6874.3 6900.0 6940.6 6946.2 6951.9 6410.7 6554.5 6556.5 6568.3 6648.6 6549.4 6575.3 6581.8 6607.4 6643.1 6694.7 6713.3 6713.9 6720.2 6741.9 6128.0 6213.9 6228.1 6228.5 6261.7 6244.2 6268.2 6287.1 6288.3 6324.8 17:40:01 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | [#4] prototype.html Cycles:180 Average:5289.01 Median:5984.42 stddev:1598.95 (26.7%) stddev-sans-first:1559.70 17:40:01 INFO - PROCESS | 6655 | Values: 340.5 343.8 349.3 350.5 353.3 5961.1 6026.0 6040.3 6051.1 6073.8 5947.0 5952.3 5960.6 5961.9 5977.9 4431.8 4633.4 4634.2 4640.2 4646.6 4571.8 4572.5 4579.9 4580.7 4582.0 4501.8 4516.6 4521.9 4527.0 4528.0 4369.9 4441.2 4442.1 4449.8 4539.4 3913.1 3963.9 3973.3 3976.6 3977.5 5605.2 5606.7 5612.2 5618.2 5657.6 4975.4 5065.7 5090.4 5294.4 5298.3 6003.7 6156.6 6265.0 6274.9 6293.7 4338.3 4378.4 4381.1 4382.3 4382.7 6468.5 6489.6 6522.7 6593.6 6599.7 4494.6 4502.9 4511.3 4511.9 4513.3 4551.1 4556.6 4556.7 4558.5 4561.6 5265.1 5265.5 5268.3 5278.9 5284.9 6142.4 6294.2 6363.0 6363.4 6374.3 1588.6 1602.6 1603.7 1603.9 1605.8 1064.3 1079.8 1083.8 1102.1 1103.6 5974.0 5977.5 5982.8 5986.5 5989.7 6508.1 6529.1 6540.0 6552.3 6560.3 5978.3 5978.3 5986.0 5990.5 5990.8 3434.9 3518.6 3522.7 3526.2 3530.4 6718.3 6839.0 6839.8 6840.2 6849.0 6433.9 6762.3 6765.5 6768.0 6771.9 6244.8 6742.4 6810.7 6822.2 6825.0 6530.9 6549.6 6550.1 6557.3 6562.2 6484.9 6506.0 6509.3 6516.8 6523.9 6683.8 6807.5 6809.8 6811.1 6824.7 4441.8 4455.3 4458.3 4460.3 4468.8 6570.7 6575.1 6576.1 6578.2 6580.1 6264.0 6363.3 6410.1 6410.5 6414.6 6310.6 6384.5 6386.0 6387.8 6391.1 6284.1 6286.1 6287.6 6292.4 6294.4 6309.5 6392.2 6430.7 6442.3 6448.6 6220.3 6221.5 6226.7 6228.8 6234.8 17:40:01 INFO - PROCESS | 6655 | 17:40:01 INFO - PROCESS | 6655 | [#5] yui.html Cycles:180 Average:13115.03 Median:48346.83 stddev:15197.71 (31.4%) stddev-sans-first:15211.97 17:40:01 INFO - PROCESS | 6655 | Values: 749.1 781.7 813.0 865.2 874.6 13439.9 13599.3 13725.1 13730.5 13740.0 12799.3 12910.8 12911.0 12921.2 12926.2 664.2 672.6 780.8 786.9 805.4 808.8 816.0 816.2 816.5 818.1 839.1 844.8 845.0 845.3 847.2 686.0 695.2 831.1 844.4 844.9 622.3 624.8 624.9 627.7 630.3 12884.0 13011.8 13029.5 13037.3 13055.5 11406.9 11626.6 11809.9 11908.3 12358.6 10185.2 10294.3 10357.2 10516.6 10608.6 3559.7 3617.7 3643.1 3663.6 3737.2 45130.5 45561.5 45948.8 46136.5 46247.8 10316.4 10358.5 10414.1 10479.8 10638.9 18636.0 18640.8 18654.3 18657.3 18658.1 7495.1 7513.2 7623.9 7634.2 7762.8 4278.4 4356.4 4408.7 4418.2 4425.4 2953.1 2984.4 2988.5 2990.5 2992.8 526.5 528.1 534.7 539.9 546.8 537.3 563.9 575.9 576.9 586.8 6951.1 7028.0 7236.2 7285.6 7362.8 560.8 567.7 574.9 588.0 600.5 3373.7 3504.4 3518.1 3582.4 3591.2 53281.5 53521.7 53814.0 53831.5 54332.5 48198.3 48495.4 48816.5 49586.5 50294.7 43757.8 44122.8 44674.8 44849.0 45339.8 6996.4 7011.0 7055.6 7163.9 7265.4 33842.2 34372.8 34462.3 34820.0 35215.3 37222.6 42728.0 42989.6 43177.6 43216.0 9499.1 9575.8 9580.1 9671.7 9899.5 10132.3 10403.4 10556.3 10712.7 10719.1 9736.1 10213.4 10252.7 10295.9 10352.7 9479.5 9819.8 9930.8 10156.5 10179.7 8040.3 10053.9 10602.2 10646.4 11004.6 9261.5 9316.6 9547.4 9642.5 9855.4 8767.4 8887.6 9252.1 9343.7 9577.1 17:40:01 INFO - PROCESS | 6655 | -------- Summary: end -------- 17:40:01 INFO - PROCESS | 6655 | 17:40:02 INFO - TEST-INFO | 6655: exit 0 17:40:02 INFO - TEST-OK | dromaeo_css | took 1113363ms 17:40:02 INFO - TEST-START | kraken 17:40:02 INFO - operating with platform_type : linux_ 17:40:02 INFO - Initialising browser for kraken test... 17:40:02 INFO - TEST-INFO | started process 6842 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRD1Ick/profile http://localhost:40892/getInfo.html) 17:40:06 INFO - PROCESS | 6842 | __metrics Screen width/height:1600/1200 17:40:06 INFO - PROCESS | 6842 | colorDepth:24 17:40:06 INFO - PROCESS | 6842 | Browser inner width/height: 1024/697 17:40:06 INFO - PROCESS | 6842 | __metrics 17:40:06 INFO - PROCESS | 6842 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:40:06 INFO - PROCESS | 6842 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:40:08 INFO - TEST-INFO | 6842: exit 0 17:40:08 INFO - Browser initialized. 17:40:08 INFO - Running cycle 1/1 for kraken test... 17:40:08 INFO - TEST-INFO | started process 6924 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpRD1Ick/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:40:19 INFO - PROCESS | 6924 | RSS: Main: 148742144 17:40:19 INFO - PROCESS | 6924 | 17:40:22 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40892/tests/kraken/driver.html?testName=audio-beat-detection) 17:40:22 INFO - PROCESS | 6924 | RSS: Main: 185118720 17:40:22 INFO - PROCESS | 6924 | 17:40:25 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40892/tests/kraken/driver.html?testName=audio-dft) 17:40:26 INFO - PROCESS | 6924 | RSS: Main: 173072384 17:40:26 INFO - PROCESS | 6924 | 17:40:29 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40892/tests/kraken/driver.html?testName=audio-fft) 17:40:29 INFO - PROCESS | 6924 | RSS: Main: 158683136 17:40:29 INFO - PROCESS | 6924 | 17:40:31 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40892/tests/kraken/driver.html?testName=audio-oscillator) 17:40:32 INFO - PROCESS | 6924 | RSS: Main: 161521664 17:40:32 INFO - PROCESS | 6924 | 17:40:34 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40892/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:40:34 INFO - PROCESS | 6924 | RSS: Main: 164700160 17:40:34 INFO - PROCESS | 6924 | 17:40:37 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40892/tests/kraken/driver.html?testName=imaging-darkroom) 17:40:38 INFO - PROCESS | 6924 | RSS: Main: 163676160 17:40:38 INFO - PROCESS | 6924 | 17:40:41 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40892/tests/kraken/driver.html?testName=imaging-desaturate) 17:40:42 INFO - PROCESS | 6924 | RSS: Main: 165879808 17:40:42 INFO - PROCESS | 6924 | 17:40:45 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40892/tests/kraken/driver.html?testName=json-parse-financial) 17:40:45 INFO - PROCESS | 6924 | RSS: Main: 165076992 17:40:45 INFO - PROCESS | 6924 | 17:40:47 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40892/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:40:47 INFO - PROCESS | 6924 | RSS: Main: 177086464 17:40:47 INFO - PROCESS | 6924 | 17:40:49 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:40:50 INFO - PROCESS | 6924 | RSS: Main: 176357376 17:40:50 INFO - PROCESS | 6924 | 17:40:52 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:40:52 INFO - PROCESS | 6924 | RSS: Main: 188178432 17:40:52 INFO - PROCESS | 6924 | 17:40:55 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:40:55 INFO - PROCESS | 6924 | RSS: Main: 192618496 17:40:55 INFO - PROCESS | 6924 | 17:40:58 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:40:58 INFO - PROCESS | 6924 | RSS: Main: 190664704 17:40:58 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | Cycle 1(1): loaded http://localhost:40892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40892/tests/kraken/driver.html?testName=ai-astar) 17:41:00 INFO - PROCESS | 6924 | RSS: Main: 248303616 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | __start_tp_report 17:41:00 INFO - PROCESS | 6924 | _x_x_mozilla_page_load 17:41:00 INFO - PROCESS | 6924 | _x_x_mozilla_page_load_details 17:41:00 INFO - PROCESS | 6924 | |i|pagename|runs| 17:41:00 INFO - PROCESS | 6924 | |0;ai-astar;109;112;107;110;112;110;100;110;110;101 17:41:00 INFO - PROCESS | 6924 | |1;audio-beat-detection;149;125;155;161;127;153;158;128;165;136 17:41:00 INFO - PROCESS | 6924 | |2;audio-dft;161;160;162;161;163;162;162;160;163;162 17:41:00 INFO - PROCESS | 6924 | |3;audio-fft;74;71;80;90;71;71;71;103;94;72 17:41:00 INFO - PROCESS | 6924 | |4;audio-oscillator;108;97;142;96;97;97;143;96;96;136 17:41:00 INFO - PROCESS | 6924 | |5;imaging-gaussian-blur;110;109;109;109;109;121;109;109;110;110 17:41:00 INFO - PROCESS | 6924 | |6;imaging-darkroom;145;146;146;146;145;145;145;146;146;146 17:41:00 INFO - PROCESS | 6924 | |7;imaging-desaturate;96;96;96;96;96;95;96;95;96;96 17:41:00 INFO - PROCESS | 6924 | |8;json-parse-financial;59;58;58;57;58;57;57;68;57;57 17:41:00 INFO - PROCESS | 6924 | |9;json-stringify-tinderbox;67;69;68;68;69;68;68;67;69;70 17:41:00 INFO - PROCESS | 6924 | |10;stanford-crypto-aes;85;85;86;94;86;87;89;88;87;87 17:41:00 INFO - PROCESS | 6924 | |11;stanford-crypto-ccm;119;117;124;121;123;117;115;133;122;124 17:41:00 INFO - PROCESS | 6924 | |12;stanford-crypto-pbkdf2;148;143;151;151;152;149;153;152;154;141 17:41:00 INFO - PROCESS | 6924 | |13;stanford-crypto-sha256-iterative;69;68;68;67;67;67;67;68;68;67 17:41:00 INFO - PROCESS | 6924 | __end_tp_report 17:41:00 INFO - PROCESS | 6924 | __start_cc_report 17:41:00 INFO - PROCESS | 6924 | _x_x_mozilla_cycle_collect,935 17:41:00 INFO - PROCESS | 6924 | __end_cc_report 17:41:00 INFO - PROCESS | 6924 | __startTimestamp1451439660911__endTimestamp 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | ------- Summary: start ------- 17:41:00 INFO - PROCESS | 6924 | Number of tests: 14 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#0] ai-astar Cycles:10 Average:108.10 Median:110.00 stddev:4.25 (3.9%) stddev-sans-first:4.50 17:41:00 INFO - PROCESS | 6924 | Values: 109.0 112.0 107.0 110.0 112.0 110.0 100.0 110.0 110.0 101.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:154.00 stddev:15.25 (9.9%) stddev-sans-first:16.13 17:41:00 INFO - PROCESS | 6924 | Values: 149.0 125.0 155.0 161.0 127.0 153.0 158.0 128.0 165.0 136.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 17:41:00 INFO - PROCESS | 6924 | Values: 161.0 160.0 162.0 161.0 163.0 162.0 162.0 160.0 163.0 162.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#3] audio-fft Cycles:10 Average:79.70 Median:73.00 stddev:11.78 (16.1%) stddev-sans-first:12.31 17:41:00 INFO - PROCESS | 6924 | Values: 74.0 71.0 80.0 90.0 71.0 71.0 71.0 103.0 94.0 72.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#4] audio-oscillator Cycles:10 Average:110.80 Median:96.00 stddev:20.77 (21.6%) stddev-sans-first:22.00 17:41:00 INFO - PROCESS | 6924 | Values: 108.0 97.0 142.0 96.0 97.0 97.0 143.0 96.0 96.0 136.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#5] imaging-gaussian-blur Cycles:10 Average:110.50 Median:109.50 stddev:3.72 (3.4%) stddev-sans-first:3.94 17:41:00 INFO - PROCESS | 6924 | Values: 110.0 109.0 109.0 109.0 109.0 121.0 109.0 109.0 110.0 110.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 17:41:00 INFO - PROCESS | 6924 | Values: 145.0 146.0 146.0 146.0 145.0 145.0 145.0 146.0 146.0 146.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:41:00 INFO - PROCESS | 6924 | Values: 96.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:58.00 stddev:3.37 (5.8%) stddev-sans-first:3.57 17:41:00 INFO - PROCESS | 6924 | Values: 59.0 58.0 58.0 57.0 58.0 57.0 57.0 68.0 57.0 57.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.50 stddev:0.95 (1.4%) stddev-sans-first:0.88 17:41:00 INFO - PROCESS | 6924 | Values: 67.0 69.0 68.0 68.0 69.0 68.0 68.0 67.0 69.0 70.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:87.00 stddev:2.63 (3.0%) stddev-sans-first:2.65 17:41:00 INFO - PROCESS | 6924 | Values: 85.0 85.0 86.0 94.0 86.0 87.0 89.0 88.0 87.0 87.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#11] stanford-crypto-ccm Cycles:10 Average:121.50 Median:122.50 stddev:5.13 (4.2%) stddev-sans-first:5.36 17:41:00 INFO - PROCESS | 6924 | Values: 119.0 117.0 124.0 121.0 123.0 117.0 115.0 133.0 122.0 124.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.40 Median:151.50 stddev:4.30 (2.8%) stddev-sans-first:4.53 17:41:00 INFO - PROCESS | 6924 | Values: 148.0 143.0 151.0 151.0 152.0 149.0 153.0 152.0 154.0 141.0 17:41:00 INFO - PROCESS | 6924 | 17:41:00 INFO - PROCESS | 6924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.53 17:41:00 INFO - PROCESS | 6924 | Values: 69.0 68.0 68.0 67.0 67.0 67.0 67.0 68.0 68.0 67.0 17:41:00 INFO - PROCESS | 6924 | -------- Summary: end -------- 17:41:00 INFO - PROCESS | 6924 | 17:41:01 INFO - TEST-INFO | 6924: exit 0 17:41:01 INFO - TEST-OK | kraken | took 59203ms 17:41:01 INFO - TEST-START | v8_7 17:41:01 INFO - operating with platform_type : linux_ 17:41:01 INFO - Initialising browser for v8_7 test... 17:41:01 INFO - TEST-INFO | started process 7177 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_4nHjl/profile http://localhost:40892/getInfo.html) 17:41:06 INFO - PROCESS | 7177 | __metrics Screen width/height:1600/1200 17:41:06 INFO - PROCESS | 7177 | colorDepth:24 17:41:06 INFO - PROCESS | 7177 | Browser inner width/height: 1024/697 17:41:06 INFO - PROCESS | 7177 | __metrics 17:41:06 INFO - PROCESS | 7177 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 17:41:06 INFO - PROCESS | 7177 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:41:07 INFO - TEST-INFO | 7177: exit 0 17:41:07 INFO - Browser initialized. 17:41:07 INFO - Running cycle 1/1 for v8_7 test... 17:41:07 INFO - TEST-INFO | started process 7256 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_4nHjl/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:41:19 INFO - PROCESS | 7256 | RSS: Main: 148692992 17:41:19 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | Cycle 1(1): loaded http://localhost:40892/tests/v8_7/run.html (next: http://localhost:40892/tests/v8_7/run.html) 17:41:40 INFO - PROCESS | 7256 | RSS: Main: 546889728 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | __start_tp_report 17:41:40 INFO - PROCESS | 7256 | _x_x_mozilla_page_load 17:41:40 INFO - PROCESS | 7256 | _x_x_mozilla_page_load_details 17:41:40 INFO - PROCESS | 7256 | |i|pagename|runs| 17:41:40 INFO - PROCESS | 7256 | |0;Richards;167.2800267648043 17:41:40 INFO - PROCESS | 7256 | |1;DeltaBlue;190.65776930409913 17:41:40 INFO - PROCESS | 7256 | |2;Encrypt;280.2690582959641 17:41:40 INFO - PROCESS | 7256 | |3;Decrypt;5175.257731958763 17:41:40 INFO - PROCESS | 7256 | |4;RayTrace;1966.6011787819255 17:41:40 INFO - PROCESS | 7256 | |5;Earley;801.2820512820513 17:41:40 INFO - PROCESS | 7256 | |6;Boyer;12168.674698795181 17:41:40 INFO - PROCESS | 7256 | |7;RegExp;26394.736842105263 17:41:40 INFO - PROCESS | 7256 | |8;Splay;601.3229104028864 17:41:40 INFO - PROCESS | 7256 | |9;NavierStokes;4840.579710144928 17:41:40 INFO - PROCESS | 7256 | __end_tp_report 17:41:40 INFO - PROCESS | 7256 | __startTimestamp1451439700946__endTimestamp 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | ------- Summary: start ------- 17:41:40 INFO - PROCESS | 7256 | Number of tests: 10 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#0] Richards Cycles:1 Average:167.28 Median:167.28 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 167.3 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#1] DeltaBlue Cycles:1 Average:190.66 Median:190.66 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 190.7 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#2] Encrypt Cycles:1 Average:280.27 Median:280.27 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 280.3 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 5175.3 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#4] RayTrace Cycles:1 Average:1966.60 Median:1966.60 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 1966.6 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#5] Earley Cycles:1 Average:801.28 Median:801.28 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 801.3 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#6] Boyer Cycles:1 Average:12168.67 Median:12168.67 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 12168.7 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#7] RegExp Cycles:1 Average:26394.74 Median:26394.74 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 26394.7 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#8] Splay Cycles:1 Average:601.32 Median:601.32 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 601.3 17:41:40 INFO - PROCESS | 7256 | 17:41:40 INFO - PROCESS | 7256 | [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 17:41:40 INFO - PROCESS | 7256 | Values: 4840.6 17:41:40 INFO - PROCESS | 7256 | -------- Summary: end -------- 17:41:40 INFO - PROCESS | 7256 | 17:41:41 INFO - TEST-INFO | 7256: exit 0 17:41:41 INFO - TEST-OK | v8_7 | took 39815ms 17:41:41 INFO - SUITE-END | took 1212s 17:41:41 INFO - Completed test suite (00:20:12) 17:41:41 INFO - Working with test: dromaeo_css 17:41:41 INFO - Generating results file: dromaeo_css 17:41:41 INFO - Working with test: kraken 17:41:41 INFO - Generating results file: kraken 17:41:41 INFO - javascript benchmark 17:41:41 INFO - Working with test: v8_7 17:41:41 INFO - Generating results file: v8_7 17:41:41 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:41:42 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 17:41:42 INFO - process_Request line: dromaeo_css 6836.95 graph.html#tests=[[72,201,35]] 17:41:42 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:41:42 INFO - process_Request line: kraken 1510.60 graph.html#tests=[[232,201,35]] 17:41:42 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 17:41:42 INFO - process_Request line: v8_7 19772.38 graph.html#tests=[[230,201,35]] 17:41:42 INFO - RETURN: dromaeo_css: 6836.95 17:41:42 INFO - RETURN: kraken: 1510.60 17:41:42 INFO - RETURN: v8_7: 19772.38 17:41:42 INFO - javascript benchmark 17:41:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3144.276816332244, 3188.7767856250043, 3230.7899986500875, 3237.223066464048, 3258.6578409267026, 5160.096983027974, 5229.372475302965, 5232.502912223342, 5285.339332583427, 5308.708021058845, 5253.86865328366, 5318.282031925689, 5319.547838433728, 5324.201369794523, 5337.733113344337, 4660.953390466103, 4746.403862425966, 4768.546988036131, 4878.243872199815, 4948.430930443009, 4710.481846996828, 4878.878028049292, 4901.754912254373, 4939.604831613462, 4947.529984651453, 4779.450363208241, 4808.831690890819, 4848.0788650156355, 4873.829415970459, 4878.585320247783, 4854.878628034292, 4856.05306965142, 4861.781219845116, 4886.804527818883, 4903.4851340609075, 4715.269133284346, 4825.131476334259, 4832.613390928753, 4870.269459581055, 4888.755562221874, 4996.200607902754, 5006.4492905780335, 5086.18621020635, 5093.439721630618, 5128.512791284822, 5695.259616249861, 5697.145428185805, 5707.058335374658, 5709.457601527848, 5713.914291285592, 5528.6406383585345, 5692.203091567184, 5740.995325817965, 5742.483176514092, 5748.42515748426, 5263.421023687391, 5321.308229930084, 5342.305500284938, 5359.946400536022, 5362.865928351784, 5776.537876969833, 5788.971055144697, 5790.42095790426, 5793.362730099686, 5798.884022319615, 5465.945340546623, 5562.944370556243, 5571.637843540157, 5577.9163312550345, 5587.078132108157, 5567.972160139173, 5619.971900140473, 5620.97189514058, 5744.684042377709, 5750.1949727038245, 5597.356304025007, 5601.495865372136, 5601.719914004365, 5607.775688972477, 5610.298712660918, 5565.3599836019375, 5612.242347283146, 5614.2701448811395, 5614.9157762633595, 5618.325800903917, 4332.176886391576, 4369.798155993023, 4414.867553973386, 4418.889527761768, 4419.668524860648, 3899.1421887184774, 3947.111899822517, 3955.545112312121, 3976.920461590752, 3977.960220397817, 5339.519443250126, 5342.2787923630585, 5342.358916929993, 5347.064263753828, 5365.3293338332705, 5738.684372359477, 5741.138829175574, 5743.253377060955, 5744.138379243063, 5746.597738158289, 5167.069927413102, 5216.37403511581, 5265.341832270966, 5271.4728527147745, 5370.785168593291, 4915.21356582945, 4947.0, 4948.653594248368, 4959.181735013754, 4974.90050198994, 5893.182348241647, 5913.408659134138, 5913.822585322448, 5929.52563794904, 5966.254218222723, 5613.438656134354, 5613.438656134354, 5687.459691329317, 5712.257406537124, 5721.370649228665, 5899.0, 5900.055991041413, 5900.144479050427, 5945.10823376497, 5960.940390596039, 5601.383847776659, 5606.719664016864, 5608.355039170548, 5610.354809196994, 5629.718514074362, 5562.860928476821, 5625.803096891589, 5643.266375371176, 5720.8283751487525, 5724.169995350734, 5529.972350138223, 5574.136008918624, 5575.637583557056, 5607.859803504945, 5657.60396772222, 5598.3282006159525, 5619.044762390486, 5640.60515763909, 5653.604247702786, 5658.603897727119, 5414.485623865727, 5505.752241149078, 5528.308961379827, 5555.2222688822785, 5566.693831839288, 5483.8080667176455, 5570.637908535932, 5669.716514174357, 5681.659100453986, 5714.885702286014, 5377.677339359651, 5384.219288203267, 5384.569234461155, 5390.51485366319, 5443.210734443404], "unit": "score", "name": "dojo.html", "value": 5240.817711788543}, {"lowerIsBetter": false, "replicates": [520.8099043848996, 530.1782237531825, 533.0644718518998, 556.1213283012837, 557.4007941462927, 27522.86238568807, 28104.718952810465, 28237.435251294988, 35187.64812351876, 35566.8221658892, 37649.0, 38224.0, 38269.617303826955, 38283.617163828356, 38284.0, 8316.04365497968, 8467.449615774985, 8471.06818249992, 8474.0, 8475.491470511759, 7616.885746713804, 7774.766756997299, 7787.883181752279, 7790.922090779076, 8273.214044665778, 7424.628768561551, 10058.145057670106, 10282.177425805956, 10293.48532573372, 10304.536295866666, 9926.25553083518, 10011.849822252672, 10016.54925528353, 10023.298369114165, 10032.448215348226, 7576.128745194318, 7767.805804854867, 9777.41335519871, 9931.65239216624, 9936.652217172365, 38515.229695405935, 38864.22271554553, 39142.21715565701, 39218.21563568741, 39228.803855980535, 3235.7896736712037, 3262.8368581570826, 3344.0803778960735, 3462.290230502741, 3739.196072844324, 26686.46627067448, 26688.19935401941, 26823.0, 26865.73134268652, 27732.584011239847, 3834.559025712051, 3851.114243723959, 3865.8016015035146, 3927.2538217738825, 3983.143624120828, 214439.0, 215197.0, 215261.0, 215599.0, 215809.0, 55071.173932391044, 55097.44902551003, 55212.17181742277, 55553.72223138859, 55674.44325556693, 57267.71366143226, 57520.137197942066, 57588.71205643945, 57653.0, 57666.7116664414, 13407.93296033533, 13478.932605337106, 13581.185128892299, 13581.45674173022, 13591.116577422436, 11157.051650609626, 11170.497327620276, 11171.162162837658, 11171.888281117084, 11184.496697648625, 18616.363271872295, 19423.44867341515, 19493.512662183275, 19501.90249048774, 19535.49596625399, 7479.0, 7592.354649854714, 7709.38324934004, 7742.14836367999, 7754.806129846799, 990.1929927109383, 1047.675220681591, 1051.6109039655375, 1078.3152698036804, 1094.2067001423904, 28559.85720071428, 28608.427831443256, 28927.85536072306, 29199.41601167965, 29626.851865740533, 996.9102780749622, 1022.544967489475, 1041.7551875309296, 1086.163653986426, 1120.697411698853, 13779.793303100461, 13995.23026233567, 14056.648583785487, 14095.0, 14172.94492298611, 306712.0, 308942.0, 311967.0, 312290.0, 312324.0, 38700.22599547993, 50129.248061278384, 51335.48664513307, 51359.74320128375, 51589.0, 1327.4557431452865, 1335.833020872391, 1340.0887396570427, 1365.1740696878444, 1434.5624584501618, 25585.744142558335, 26187.476250475265, 27422.0, 28921.034479311482, 29200.707992919797, 45480.54519454763, 46289.0, 46310.768446157555, 46340.30489542727, 46379.53620463752, 191288.0, 191639.0, 191682.0, 191880.0, 191994.0, 30532.236694082825, 30630.08109756711, 30650.233744156576, 30824.229394265323, 31007.68992310048, 41991.3701294487, 42606.786966064974, 43143.56856431521, 43265.35101973536, 43266.35100473558, 34522.482162767075, 35501.28997420089, 35890.820545897106, 36805.0, 37084.0, 35857.64142358543, 36039.27921441504, 36237.818810905774, 36744.63255367412, 37005.81497092497, 32138.517922230716, 33047.17382065371, 33096.0, 33114.0, 33165.33669326648, 29402.0, 31648.0, 31799.410029498897, 31858.362832743678, 32110.839445802623, 23900.0, 23953.56278623288, 24019.399515012265, 24599.87700061488, 24802.50394992126], "unit": "score", "name": "ext.html", "value": 17650.095856430707}, {"lowerIsBetter": false, "replicates": [350.9631488693687, 359.3944204016231, 360.6375592529507, 362.2121884900342, 365.30591875436676, 13315.733685326288, 13646.270745850845, 15976.520704378872, 16741.58146046348, 17700.964389095243, 16048.518544443656, 16905.323787048503, 17246.05146716933, 17249.91375043126, 17339.653206935884, 5278.683279003262, 5403.756830942617, 5410.2966614340085, 5413.566914646819, 5414.377346605132, 4036.1120553478336, 4148.771817550033, 5362.35651721794, 5409.080456322435, 5783.768649254046, 5954.880902381971, 5992.161097446361, 6011.248593925759, 6020.12708157317, 6024.126501657257, 5267.683938963655, 5389.461053894618, 5430.212619170237, 5431.809886653988, 5966.313873904492, 5176.715280659562, 5187.636865419385, 5196.818111366084, 5199.818006369796, 5210.583153347761, 15835.524934251991, 20053.79946200534, 20388.388348349574, 20453.897730511402, 20569.382918512467, 2049.5388537579074, 2072.554400803819, 2123.77700341465, 2153.558520503293, 2192.9780702192934, 9680.0, 9698.563564639608, 9857.457839818808, 9894.90105098947, 12522.624321270376, 1789.677857985562, 1841.1438681013392, 1870.728744332078, 1881.7930027696943, 1884.5759704066613, 57984.710076449766, 60271.69864150695, 60275.0, 60764.69617651883, 61111.38888611146, 9504.002079781523, 9558.95220523893, 9571.425714457155, 9623.56693948774, 9642.95178524117, 12500.062495312888, 12515.561955331701, 12522.186057906207, 12537.373131343578, 12562.120651554304, 7257.201707812136, 7333.853322933512, 7437.550804645813, 7491.8501629967095, 7513.39892808585, 7280.745173918887, 7317.195108538057, 7533.209013053551, 7596.2023987481625, 7641.847163056707, 4448.733076015449, 4531.048479819275, 4542.500324964251, 4555.06621142665, 4555.407796986437, 1726.5769886377918, 1768.3810666266704, 1797.766290382242, 1813.3381315819895, 1874.004185542393, 915.8992510823804, 972.6060945317158, 982.5873133283895, 986.4426598971559, 1050.7653507129246, 16746.6650666986, 17061.0, 17104.828951710573, 17393.416376280773, 17570.472885813448, 900.9459432434074, 926.4765407544716, 951.7573018880141, 952.2857856607544, 1018.5467466977273, 8879.28965682744, 10023.749406264902, 10082.19342452602, 10083.697489075339, 10102.646407375854, 386356.0, 396023.0, 555851.0, 563060.0, 564985.0, 71270.64364678247, 76380.23619763843, 82301.41588672892, 84195.57902210573, 85084.0, 1123.230587047875, 1149.6608500492375, 1155.6821873984554, 1157.9015783658315, 1157.9826302605468, 15683.215839208222, 15940.282687279332, 16072.0, 16090.517284481484, 16209.108499032667, 34423.82788086044, 35087.0, 35131.64868351284, 36143.63856361508, 36380.0, 47682.80792980203, 47874.76062619664, 48790.0, 49996.75034121365, 50401.49598503968, 13745.587632371045, 14689.41242350294, 15036.548903532912, 15424.768628470358, 15478.767818482507, 13332.866671333162, 18217.180226889555, 18492.907535462236, 19170.04149792477, 19215.80784192196, 9676.806463870824, 10030.448325342177, 10037.49812509357, 15340.616484587974, 15515.301811418287, 9907.157891579296, 10161.898381016392, 10230.539625716712, 10283.588656453658, 10496.632617858339, 10076.395416275047, 10387.584496620051, 10405.739856503647, 10422.322549034288, 10527.684169474927, 14392.424303027761, 14574.854251457773, 15013.849861501245, 15046.774298385313, 15079.095254284777, 15220.086794792347, 15262.923685381502, 15304.311305991034, 15649.92175039163, 15833.287502062205], "unit": "score", "name": "jquery.html", "value": 8929.64063017099}, {"lowerIsBetter": false, "replicates": [1334.372844762961, 1467.7431449496341, 1481.1779462398367, 1616.8302328255534, 1775.9112044397784, 6662.433693136083, 7056.717731290749, 7156.892646610305, 7207.207207207203, 7214.314640109181, 7061.611611361386, 7232.13214414271, 7247.601381923992, 7250.818729531751, 7260.564366138022, 2222.277759728087, 2238.764929682376, 2251.2908433843286, 2252.583272094668, 2257.559775843711, 2255.4812393149587, 2269.909203631853, 2322.1872344679396, 2322.5354929014266, 2322.8025617822505, 2230.542738738563, 2232.3079845248026, 2259.1189436119926, 2264.8188144948363, 2303.9078436862505, 2220.777922207766, 2246.921357752479, 2254.8534345267503, 2268.6029944759603, 2272.6931864198286, 2246.696695946043, 2259.197984715419, 2259.988700056489, 2260.9660855087186, 2263.864168149916, 7177.923311503264, 7238.059052323217, 7244.529105608171, 7244.71021159153, 7251.5286506376915, 4495.842645507424, 4536.77316134192, 4549.6360291176625, 4585.541445855388, 4596.701214421052, 2764.2536515141, 2956.305268261957, 2967.540031295131, 2979.746721528673, 2995.1014695591452, 932.2262522106636, 942.3780304998668, 959.1271942532331, 975.3075316525204, 981.0336818234034, 9981.251406144569, 9995.100440960348, 10001.749956251151, 10013.399196048187, 10015.89984100157, 4085.816138273785, 4138.875833724993, 4148.377743338523, 4167.874963751093, 4210.578942105765, 8644.049154592989, 8646.567671616394, 8675.305975521944, 8689.869651955225, 8720.084391138838, 4611.538846115362, 4619.930701039487, 4622.884427889329, 4626.0, 4636.466806317317, 3683.2633473305445, 3810.9809450952566, 3989.2819292527624, 4259.488861336659, 4265.7440553566885, 3506.7895926243996, 3518.806465644414, 3544.486049522805, 3545.2023294758474, 3573.088862340139, 1119.367557330106, 1141.366541569437, 1153.901918336934, 1159.5245949160803, 1169.3626973299401, 1337.4382759241141, 1345.9259740714356, 1357.4434481862388, 1373.7733274009677, 1375.6835927736477, 6750.763723269662, 6951.304869513109, 7162.319579639926, 7176.677049532679, 7179.820504487429, 1120.9439528023565, 1137.5279259107542, 1138.573035111833, 1146.7075895646724, 1168.567629976914, 5562.0, 5605.7197140142825, 5607.4112218217315, 5612.214289999403, 5613.887722245532, 8469.872951905727, 8518.48889066658, 8681.522516261666, 8792.780180495538, 8866.113388661212, 8808.51553164582, 8858.468491890508, 8886.422382545114, 8887.066857979948, 8939.865902011476, 6711.261761206263, 6769.323067693192, 6786.69459874297, 7041.366277035091, 7130.714771409189, 6825.522213445011, 6883.483738719716, 6885.586864788129, 6886.139232595926, 6890.379865812101, 7652.846943060997, 7794.727184548514, 7806.687732490636, 7868.25251601097, 7914.683412663429, 7735.0, 8249.29880960125, 8374.162583741578, 8381.0, 8394.58027098655, 5965.28416590012, 6292.9370706294185, 6541.542092053511, 6626.237982632059, 6711.0940023096255, 6874.278200788845, 6900.0, 6940.583564986117, 6946.235914049349, 6951.860962780817, 6410.679466026587, 6554.541182117206, 6556.508261880283, 6568.343165683394, 6648.6343251119715, 6549.410553050248, 6575.342465753386, 6581.835454113685, 6607.405333520006, 6643.136392268974, 6694.698738556874, 6713.261541230559, 6713.932860671331, 6720.193576770819, 6741.86516269662, 6127.969360153171, 6213.9067913982235, 6228.0969259456215, 6228.532860035425, 6261.74953001893, 6244.157038799885, 6268.216472940882, 6287.119803227643, 6288.339724329063, 6324.778632747832], "unit": "score", "name": "mootools.html", "value": 4332.677056204796}, {"lowerIsBetter": false, "replicates": [340.4875662128497, 343.8435511842112, 349.3153419298175, 350.4847873625769, 353.30399113745926, 5961.105834124878, 6026.0, 6040.3053648830455, 6051.122587224855, 6073.817785466443, 5947.01874190758, 5952.255968003999, 5960.6423614583155, 5961.910571341434, 5977.940220597793, 4431.800568974404, 4633.443986721598, 4634.165850146972, 4640.164770341337, 4646.628269738413, 4571.817127314904, 4572.451305843303, 4579.908401831962, 4580.748058856761, 4582.0, 4501.842435514757, 4516.570925762064, 4521.864344069683, 4526.97736511317, 4528.04910968697, 4369.934450983238, 4441.222786012435, 4442.133783912139, 4449.777511124463, 4539.4098767160385, 3913.099987002977, 3963.8612648557164, 3973.284808734427, 3976.5824588418377, 3977.5226972763453, 5605.243292155547, 5606.747696353674, 5612.214289999403, 5618.185363122325, 5657.575681823839, 4975.427825800043, 5065.746712664389, 5090.38915330159, 5294.364676238837, 5298.258243845826, 6003.699815009232, 6156.5998210116195, 6264.966280563702, 6274.87450250997, 6293.748250069992, 4338.3058710606465, 4378.387025816388, 4381.07997320563, 4382.342648602703, 4382.737035777832, 6468.514861385415, 6489.643069631168, 6522.706478208492, 6593.571417857824, 6599.736010559572, 4494.5505449454795, 4502.932456013163, 4511.255642818898, 4511.88720281989, 4513.277875539898, 4551.112533056023, 4556.567126123013, 4556.749378784199, 4558.452985641744, 4561.566651168134, 5265.052290587731, 5265.473452654704, 5268.288781014514, 5278.9472105278455, 5284.894302113936, 6142.3550527194, 6294.244690637152, 6363.0, 6363.395477429637, 6374.330695277022, 1588.602849287678, 1602.5512856400226, 1603.6952978934198, 1603.885299716709, 1605.7992750906137, 1064.2550214849637, 1079.8488211650217, 1083.7507373304184, 1102.0522350778485, 1103.6082190822303, 5974.0, 5977.521798256129, 5982.790602328898, 5986.5210783137245, 5989.730462129215, 6508.088867558546, 6529.053287273318, 6540.0, 6552.344765523505, 6560.278369379364, 5978.282606087298, 5978.342382337939, 5986.042233242661, 5990.460858522667, 5990.760369585168, 3434.862605495802, 3518.5777706675362, 3522.682958533693, 3526.2242306692488, 3530.3645343837843, 6718.260991290954, 6839.0425340452375, 6839.760608378683, 6840.213375461786, 6849.04113424121, 6433.935660643428, 6762.289959554274, 6765.52641315113, 6768.0, 6771.93228067713, 6244.782267457802, 6742.42689371409, 6810.7059658665685, 6822.181338239443, 6825.0, 6530.869382612416, 6549.639769812706, 6550.082988381726, 6557.278699343164, 6562.245341785756, 6484.902726459201, 6505.967470162619, 6509.251436084911, 6516.771912983023, 6523.902141467976, 6683.7660681875905, 6807.455403567604, 6809.795706128824, 6811.148606424197, 6824.658767061726, 4441.844535441244, 4455.287154055336, 4458.264386376211, 4460.330950357472, 4468.754218517883, 6570.704318305783, 6575.145231119924, 6576.112224849584, 6578.24350199714, 6580.144581204413, 6263.96868015657, 6363.331850155667, 6410.134631824644, 6410.5192110592825, 6414.647194404352, 6310.558260921875, 6384.489240860627, 6385.96807015962, 6387.808365749035, 6391.073294372383, 6284.120223168668, 6286.088517164894, 6287.622742635456, 6292.433680968735, 6294.402031807154, 6309.4952403806665, 6392.169018027626, 6430.710618022106, 6442.2591401989375, 6448.580842245239, 6220.284667263141, 6221.502279817694, 6226.688665566794, 6228.813135605939, 6234.812955611339], "unit": "score", "name": "prototype.html", "value": 4774.625280777374}, {"lowerIsBetter": false, "replicates": [749.1272667342546, 781.73811773056, 813.0284310249252, 865.1521508921253, 874.6370256343617, 13439.865601343983, 13599.32003399831, 13725.107867988574, 13730.450781968722, 13739.969502287318, 12799.296038717866, 12910.806337904938, 12911.0, 12921.16012459192, 12926.159799613019, 664.2460806984084, 672.6199697171107, 780.8360244348679, 786.9370450363986, 805.4120492040814, 808.8261023879865, 816.0493025625169, 816.2205094135116, 816.5019338203691, 818.0510607695074, 839.1063517354029, 844.8352571248578, 844.9662013519452, 845.3068483843251, 847.2247893177735, 685.9725610975555, 695.2005194026859, 831.1024093978489, 844.4131328726537, 844.890164278646, 622.3341025103141, 624.7594676049708, 624.8594066335083, 627.6987046217841, 630.338144947803, 12884.033697472632, 13011.804822927663, 13029.54396596124, 13037.282949437777, 13055.543055992996, 11406.942965285203, 11626.593069242535, 11809.881901180965, 11908.34504102274, 12358.62924112278, 10185.236107291983, 10294.279400441972, 10357.171426285879, 10516.631917882838, 10608.575656973711, 3559.661832125944, 3617.706835869748, 3643.052806270389, 3663.5603727552866, 3737.1591391936868, 45130.548694513294, 45561.54438455606, 45948.770256148506, 46136.53863461356, 46247.768761156316, 10316.43259620713, 10358.482075896174, 10414.114800242065, 10479.790404192026, 10638.946805265925, 18636.0, 18640.81359186391, 18654.34709785178, 18657.253709851455, 18658.067096645114, 7495.138059123271, 7513.248675132443, 7623.885641715378, 7634.236576342432, 7762.844743105106, 4278.382181003926, 4356.358709728868, 4408.735475871458, 4418.226810308183, 4425.380446737459, 2953.0845437913886, 2984.388200418909, 2988.4770165221, 2990.4617168909804, 2992.7605791536625, 526.4603781124379, 528.1127705454873, 534.6765207049757, 539.8947205294929, 546.7758219130217, 537.2746791830997, 563.9041362968305, 575.8675504633958, 576.881739243454, 586.7916889504164, 6951.131108611424, 7027.964860175666, 7236.240194779578, 7285.599292038883, 7362.77911662651, 560.8065217499956, 567.6764244380663, 574.9367569567344, 587.9970600147058, 600.5075837813039, 3373.7301015919156, 3504.3867323218333, 3518.0852978225844, 3582.4089025311055, 3591.1919818040733, 53281.46718532765, 53521.73239133779, 53814.0, 53831.46168538343, 54332.45667543353, 48198.27702584464, 48495.39293767086, 48816.51183488191, 49586.50413495819, 50294.74852625786, 43757.78121109374, 44122.77938610286, 44674.77662611666, 44848.999999999345, 45339.773301133944, 6996.405305549085, 7011.0, 7055.576665400093, 7163.892541611985, 7265.418766498773, 33842.15394615056, 34372.82813585916, 34462.310753785285, 34820.0, 35215.29569408649, 37222.62777372191, 42728.0, 42989.570104299804, 43177.56822431736, 43216.0, 9499.145076943109, 9575.808483830146, 9580.13778759915, 9671.661491847752, 9899.455529945923, 10132.290739648153, 10403.375797452178, 10556.313839600401, 10712.678619641423, 10719.14246860265, 9736.123748862636, 10213.438260895722, 10252.692419227435, 10295.94852025735, 10352.689419317432, 9479.47862867549, 9819.754506137404, 9930.751731206778, 10156.492175391053, 10179.694609161737, 8040.316573091352, 10053.949730251303, 10602.151827853908, 10646.361218326616, 11004.614838480615, 9261.53692315395, 9316.580753865957, 9547.379420337656, 9642.469664168539, 9855.35940163887, 8767.430117042371, 8887.600057997277, 9252.121048500381, 9343.672971445967, 9577.138057574852], "unit": "score", "name": "yui.html", "value": 5976.693804097254}], "name": "dromaeo_css", "value": 6836.952961680806}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 112.0, 107.0, 110.0, 112.0, 110.0, 100.0, 110.0, 110.0, 101.0], "unit": "score", "name": "ai-astar", "value": 108.1}, {"lowerIsBetter": true, "replicates": [149.0, 125.0, 155.0, 161.0, 127.0, 153.0, 158.0, 128.0, 165.0, 136.0], "unit": "score", "name": "audio-beat-detection", "value": 145.7}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 162.0, 161.0, 163.0, 162.0, 162.0, 160.0, 163.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.6}, {"lowerIsBetter": true, "replicates": [74.0, 71.0, 80.0, 90.0, 71.0, 71.0, 71.0, 103.0, 94.0, 72.0], "unit": "score", "name": "audio-fft", "value": 79.7}, {"lowerIsBetter": true, "replicates": [108.0, 97.0, 142.0, 96.0, 97.0, 97.0, 143.0, 96.0, 96.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 110.8}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 121.0, 109.0, 109.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.5}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [59.0, 58.0, 58.0, 57.0, 58.0, 57.0, 57.0, 68.0, 57.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 58.6}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 68.0, 68.0, 69.0, 68.0, 68.0, 67.0, 69.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.3}, {"lowerIsBetter": true, "replicates": [85.0, 85.0, 86.0, 94.0, 86.0, 87.0, 89.0, 88.0, 87.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.4}, {"lowerIsBetter": true, "replicates": [119.0, 117.0, 124.0, 121.0, 123.0, 117.0, 115.0, 133.0, 122.0, 124.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.5}, {"lowerIsBetter": true, "replicates": [148.0, 143.0, 151.0, 151.0, 152.0, 149.0, 153.0, 152.0, 154.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.4}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 68.0, 68.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.6}], "name": "kraken", "value": 1510.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.2800267648043], "unit": "score", "name": "Richards", "value": 211.03535600000004}, {"lowerIsBetter": false, "replicates": [190.65776930409913], "unit": "score", "name": "DeltaBlue", "value": 346.78891000000004}, {"lowerIsBetter": false, "replicates": [280.2690582959641], "unit": "score", "name": "Encrypt", "value": 949.7338080000005}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1966.6011787819255], "unit": "score", "name": "RayTrace", "value": 376.2781228771229}, {"lowerIsBetter": false, "replicates": [801.2820512820513], "unit": "score", "name": "Earley", "value": 831.745824}, {"lowerIsBetter": false, "replicates": [12168.674698795181], "unit": "score", "name": "Boyer", "value": 54.768741584158384}, {"lowerIsBetter": false, "replicates": [26394.736842105263], "unit": "score", "name": "RegExp", "value": 34.51388833499503}, {"lowerIsBetter": false, "replicates": [601.3229104028864], "unit": "score", "name": "Splay", "value": 135.519533}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 19772.38342567197}]} 17:41:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "19772.38"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1510.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6836.95"}}} 17:41:42 INFO - Return code: 0 17:41:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 17:41:42 INFO - # TBPL SUCCESS # 17:41:42 INFO - Running post-action listener: _resource_record_post_action 17:41:42 INFO - Running post-run listener: _resource_record_post_run 17:41:43 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557498368; Read time: 12; Write time: 845304 17:41:43 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 0; Write bytes: 234856448; Read time: 0; Write time: 537560 17:41:43 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 4096; Write bytes: 321777664; Read time: 12; Write time: 307700 17:41:43 INFO - Running post-run listener: _upload_blobber_files 17:41:43 INFO - Blob upload gear active. 17:41:43 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 17:41:43 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 17:41:43 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', 'B2G-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 17:41:43 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 B2G-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 17:41:43 INFO - (blobuploader) - INFO - Open directory for files ... 17:41:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:41:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:41:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:44 INFO - (blobuploader) - INFO - Done attempting. 17:41:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 17:41:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:41:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:41:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:41:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:41:45 INFO - (blobuploader) - INFO - Done attempting. 17:41:45 INFO - (blobuploader) - INFO - Iteration through files over. 17:41:45 INFO - Return code: 0 17:41:45 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 17:41:45 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 17:41:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/ac88ab068c1495540ee987476e89d92ad3fce8c1d25884ce846d458c141eb51bc8c93d89918878f44457e9b17559fae2540058c40b54f2d2b064ccde1d783f24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/a62adf68ab75577d76697caa5652d49851e6911dedd17f185a80f8c9438e150e4ac79af347db7292416afd06bbe3c9aa2f5756cdf9af51d4c2afeea61cb2c495"} 17:41:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 17:41:45 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 17:41:45 INFO - Contents: 17:41:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/ac88ab068c1495540ee987476e89d92ad3fce8c1d25884ce846d458c141eb51bc8c93d89918878f44457e9b17559fae2540058c40b54f2d2b064ccde1d783f24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/a62adf68ab75577d76697caa5652d49851e6911dedd17f185a80f8c9438e150e4ac79af347db7292416afd06bbe3c9aa2f5756cdf9af51d4c2afeea61cb2c495"} 17:41:45 INFO - Copying logs to upload dir... 17:41:45 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1274.916626 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2015-12-29 17:41:44.948909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-29 17:41:44.952981) ========= 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=01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/ac88ab068c1495540ee987476e89d92ad3fce8c1d25884ce846d458c141eb51bc8c93d89918878f44457e9b17559fae2540058c40b54f2d2b064ccde1d783f24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/a62adf68ab75577d76697caa5652d49851e6911dedd17f185a80f8c9438e150e4ac79af347db7292416afd06bbe3c9aa2f5756cdf9af51d4c2afeea61cb2c495"} build_url:https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011553 build_url: 'https://queue.taskcluster.net/v1/task/EM7H4BtfSXKRhB_HmFN1gQ/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/B2G-Inbound/sha512/ac88ab068c1495540ee987476e89d92ad3fce8c1d25884ce846d458c141eb51bc8c93d89918878f44457e9b17559fae2540058c40b54f2d2b064ccde1d783f24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/a62adf68ab75577d76697caa5652d49851e6911dedd17f185a80f8c9438e150e4ac79af347db7292416afd06bbe3c9aa2f5756cdf9af51d4c2afeea61cb2c495"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-29 17:41:44.978377) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:41:44.978714) ========= 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=01deea79d00cc2c334834894000001d2-1451436074.885498-1512474303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-29 17:41:45.061436) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-29 17:41:45.061799) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-29 17:41:45.062122) ========= ========= Total master_lag: 0.24 =========