builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1451544389.16 results: success (0) buildid: 20151230203059 builduid: bb34e5967e9a45f595bfb70b191d4941 revision: 782774a4dd2d6d659f31b47b2968d4464cee7202 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.162431) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.162918) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.163210) ========= 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=21869f6c619b95355032545b000001da-1451542489.989706-1542306809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004610 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.210523) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.210821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.255212) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.255756) ========= 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=21869f6c619b95355032545b000001da-1451542489.989706-1542306809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.040276 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.310190) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.310500) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.310873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.311153) ========= 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=21869f6c619b95355032545b000001da-1451542489.989706-1542306809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-30 22:46:29-- 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% 225M=0s 2015-12-30 22:46:29 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.241744 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.569124) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.569447) ========= 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=21869f6c619b95355032545b000001da-1451542489.989706-1542306809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.135265 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.726824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:29.727432) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 782774a4dd2d6d659f31b47b2968d4464cee7202 --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 782774a4dd2d6d659f31b47b2968d4464cee7202 --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=21869f6c619b95355032545b000001da-1451542489.989706-1542306809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-30 22:46:29,804 truncating revision to first 12 chars 2015-12-30 22:46:29,804 Setting DEBUG logging. 2015-12-30 22:46:29,804 attempt 1/10 2015-12-30 22:46:29,804 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/782774a4dd2d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-30 22:46:30,040 unpacking tar archive at: b2g-inbound-782774a4dd2d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.523563 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:30.264930) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:30.265200) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:30.271530) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:30.271809) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-30 22:46:30.272219) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-12-30 22:46:30.272859) ========= /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=21869f6c619b95355032545b000001da-1451542489.989706-1542306809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:46:30 INFO - MultiFileLogger online at 20151230 22:46:30 in /builds/slave/test-pgo 22:46: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 22:46:30 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:46:30 INFO - {'append_to_log': False, 22:46:30 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:46:30 INFO - 'blob_upload_branch': 'B2G-Inbound', 22:46:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:46:30 INFO - 'branch': 'B2G-Inbound', 22:46:30 INFO - 'buildbot_json_path': 'buildprops.json', 22:46:30 INFO - 'config_files': ('talos/linux_config.py',), 22:46:30 INFO - 'default_actions': ('clobber', 22:46:30 INFO - 'read-buildbot-config', 22:46:30 INFO - 'download-and-extract', 22:46:30 INFO - 'populate-webroot', 22:46:30 INFO - 'create-virtualenv', 22:46:30 INFO - 'install', 22:46:30 INFO - 'run-tests'), 22:46:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:46:30 INFO - 'download_minidump_stackwalk': True, 22:46:30 INFO - 'download_symbols': 'ondemand', 22:46:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:46:30 INFO - 'tooltool.py': '/tools/tooltool.py', 22:46:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:46:30 INFO - '/tools/misc-python/virtualenv.py')}, 22:46:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:46:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:46:30 INFO - 'installer_path': 'installer.exe', 22:46:30 INFO - 'log_level': 'info', 22:46:30 INFO - 'log_name': 'talos', 22:46:30 INFO - 'log_to_console': True, 22:46:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:46:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:46:30 INFO - 'opt_config_files': (), 22:46:30 INFO - 'pip_index': False, 22:46:30 INFO - 'sps_profile': False, 22:46:30 INFO - 'sps_profile_interval': 0, 22:46:30 INFO - 'suite': 'dromaeojs', 22:46:30 INFO - 'system_bits': '32', 22:46:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:46:30 INFO - 'title': 'talos-linux64-ix-003', 22:46:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:46:30 INFO - 'use_talos_json': True, 22:46:30 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:46:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:46:30 INFO - 'work_dir': 'build'} 22:46:30 INFO - ##### 22:46:30 INFO - ##### Running clobber step. 22:46:30 INFO - ##### 22:46:30 INFO - Running pre-action listener: _resource_record_pre_action 22:46:30 INFO - Running main action method: clobber 22:46:30 INFO - rmtree: /builds/slave/test-pgo/build 22:46:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:46:31 INFO - Running post-action listener: _resource_record_post_action 22:46:31 INFO - ##### 22:46:31 INFO - ##### Running read-buildbot-config step. 22:46:31 INFO - ##### 22:46:31 INFO - Running pre-action listener: _resource_record_pre_action 22:46:31 INFO - Running main action method: read_buildbot_config 22:46:31 INFO - Using buildbot properties: 22:46:31 INFO - { 22:46:31 INFO - "project": "", 22:46:31 INFO - "product": "firefox", 22:46:31 INFO - "script_repo_revision": "production", 22:46:31 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 22:46:31 INFO - "repository": "", 22:46:31 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 22:46:31 INFO - "buildid": "20151230203059", 22:46:31 INFO - "pgo_build": "True", 22:46:31 INFO - "basedir": "/builds/slave/test-pgo", 22:46:31 INFO - "buildnumber": 168, 22:46:31 INFO - "slavename": "talos-linux64-ix-003", 22:46:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:46:31 INFO - "platform": "ubuntu64_hw", 22:46:31 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 22:46:31 INFO - "branch": "b2g-inbound", 22:46:31 INFO - "repo_path": "integration/b2g-inbound", 22:46:31 INFO - "revision": "782774a4dd2d6d659f31b47b2968d4464cee7202", 22:46:31 INFO - "stage_platform": "linux64-pgo", 22:46:31 INFO - "builduid": "bb34e5967e9a45f595bfb70b191d4941", 22:46:31 INFO - "slavebuilddir": "test" 22:46:31 INFO - } 22:46:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 22:46:31 INFO - Running post-action listener: _resource_record_post_action 22:46:31 INFO - ##### 22:46:31 INFO - ##### Running download-and-extract step. 22:46:31 INFO - ##### 22:46:31 INFO - Running pre-action listener: _resource_record_pre_action 22:46:31 INFO - Running main action method: download_and_extract 22:46:31 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:46: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')]} 22:46:31 INFO - https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:46:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/test_packages.json 22:46:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/test_packages.json 22:46:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:46:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:46:35 INFO - Downloaded 1302 bytes. 22:46:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:46:35 INFO - Using the following test package requirements: 22:46:35 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:46:35 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:46:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:46:35 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:46:35 INFO - u'jsshell-linux-x86_64.zip'], 22:46:35 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:46:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:46:35 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:46:35 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:46:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:46:35 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:46:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:46:35 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:46:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:46:35 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:46:35 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:46:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:46:35 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:46: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')]} 22:46:35 INFO - https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:46:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:46:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:46:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/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 22:46:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/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 22:46:40 INFO - Downloaded 23195237 bytes. 22:46:40 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 22:46:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:46:40 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 22:46:42 INFO - Return code: 0 22:46:42 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 22:46:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:42 INFO - https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:46:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:46:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:46:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/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 22:46:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/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 22:46:43 INFO - Downloaded 23195237 bytes. 22:46:43 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 22:46:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:46:43 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 22:46:44 INFO - Return code: 0 22:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:44 INFO - https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:46:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:46:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:46:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/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 22:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/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 22:46:45 INFO - Downloaded 11132285 bytes. 22:46: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 22:46:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:46: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 22:46:45 INFO - Return code: 0 22:46: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')]} 22:46:45 INFO - https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:46:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:46:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:46:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/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 22:46:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AXFrnE21QLSf1j842PHdJw/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 22:46:47 INFO - Downloaded 54945694 bytes. 22:46:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:46:47 INFO - mkdir: /builds/slave/test-pgo/properties 22:46:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:46:47 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:46:47 INFO - Contents: 22:46:47 INFO - build_url:https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:46:47 INFO - Running post-action listener: _resource_record_post_action 22:46:47 INFO - Running post-action listener: set_extra_try_arguments 22:46:47 INFO - ##### 22:46:47 INFO - ##### Running populate-webroot step. 22:46:47 INFO - ##### 22:46:47 INFO - Running pre-action listener: _resource_record_pre_action 22:46:47 INFO - Running main action method: populate_webroot 22:46:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:46:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:46:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:46:47 INFO - '--tppagecycles', 22:46:47 INFO - '7'], 22:46:47 INFO - 'tests': ['tsvgm']}}, 22:46:47 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:46:47 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:46:47 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:46:47 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:46:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:46:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:47 INFO - 'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:46:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:47 INFO - 'tests': ['damp', 'tps']}, 22:46:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:47 INFO - 'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['damp', 'tps']}, 22:46:47 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:46:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:46:47 INFO - 'other': {'tests': ['a11yr', 22:46:47 INFO - 'ts_paint', 22:46:47 INFO - 'tpaint', 22:46:47 INFO - 'sessionrestore', 22:46:47 INFO - 'sessionrestore_no_auto_restore']}, 22:46:47 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['a11yr', 22:46:47 INFO - 'ts_paint', 22:46:47 INFO - 'tpaint', 22:46:47 INFO - 'sessionrestore', 22:46:47 INFO - 'sessionrestore_no_auto_restore']}, 22:46:47 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['a11yr', 22:46:47 INFO - 'ts_paint', 22:46:47 INFO - 'tpaint', 22:46:47 INFO - 'sessionrestore', 22:46:47 INFO - 'sessionrestore_no_auto_restore']}, 22:46:47 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['a11yr', 22:46:47 INFO - 'ts_paint', 22:46:47 INFO - 'tpaint', 22:46:47 INFO - 'sessionrestore', 22:46:47 INFO - 'sessionrestore_no_auto_restore']}, 22:46:47 INFO - 'other_l64': {'tests': ['a11yr', 22:46:47 INFO - 'ts_paint', 22:46:47 INFO - 'tpaint', 22:46:47 INFO - 'sessionrestore', 22:46:47 INFO - 'sessionrestore_no_auto_restore']}, 22:46:47 INFO - 'other_nol64': {'tests': ['a11yr', 22:46:47 INFO - 'ts_paint', 22:46:47 INFO - 'tpaint', 22:46:47 INFO - 'sessionrestore', 22:46:47 INFO - 'sessionrestore_no_auto_restore']}, 22:46:47 INFO - 'svgr': {'tests': ['tsvgx', 22:46:47 INFO - 'tsvgr_opacity', 22:46:47 INFO - 'tart', 22:46:47 INFO - 'tscrollx', 22:46:47 INFO - 'cart']}, 22:46:47 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['tsvgx', 22:46:47 INFO - 'tsvgr_opacity', 22:46:47 INFO - 'tart', 22:46:47 INFO - 'tscrollx', 22:46:47 INFO - 'cart']}, 22:46:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:47 INFO - 'tests': ['tp5o']}, 22:46:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:47 INFO - 'talos_options': ['--e10s'], 22:46:47 INFO - 'tests': ['tp5o']}, 22:46:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:47 INFO - 'talos_options': ['--xperf_path', 22:46:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:46:47 INFO - 'tests': ['tp5n']}, 22:46:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:47 INFO - 'talos_options': ['--e10s', 22:46:47 INFO - '--xperf_path', 22:46:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:46:47 INFO - 'tests': ['tp5n']}}, 22:46:47 INFO - 'talos.zip': {'path': '', 22:46:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:46:47 INFO - Running post-action listener: _resource_record_post_action 22:46:47 INFO - ##### 22:46:47 INFO - ##### Running create-virtualenv step. 22:46:47 INFO - ##### 22:46:47 INFO - Running pre-action listener: _resource_record_pre_action 22:46:47 INFO - Running main action method: create_virtualenv 22:46:47 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:46:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 22:46:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:46:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:46:47 INFO - Using real prefix '/usr' 22:46:47 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:46:47 INFO - Installing distribute.............................................................................................................................................................................................done. 22:46:49 INFO - Installing pip.................done. 22:46:49 INFO - Return code: 0 22:46:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:49 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:46:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 22:46:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:46:49 INFO - Using env: {'DISPLAY': ':0', 22:46:49 INFO - 'HOME': '/home/cltbld', 22:46:49 INFO - 'LANG': 'en_US.UTF-8', 22:46:49 INFO - 'LANGUAGE': 'en_US:en', 22:46:49 INFO - 'LOGNAME': 'cltbld', 22:46:49 INFO - 'MAIL': '/var/mail/cltbld', 22:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:49 INFO - 'MOZ_NO_REMOTE': '1', 22:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:49 INFO - 'NO_EM_RESTART': '1', 22:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:46:49 INFO - 'PWD': '/builds/slave/test-pgo', 22:46:49 INFO - 'SHELL': '/bin/bash', 22:46:49 INFO - 'SHLVL': '1', 22:46:49 INFO - 'TERM': 'linux', 22:46:49 INFO - 'TMOUT': '86400', 22:46:49 INFO - 'USER': 'cltbld', 22:46:49 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:49 INFO - '_': '/tools/buildbot/bin/python'} 22:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:50 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 22:46:50 INFO - Cleaning up... 22:46:50 INFO - Return code: 0 22:46:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:46:50 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')]} 22:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:50 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')]} 22:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:50 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 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:46:50 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 22:46:50 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 22:46:50 INFO - Using env: {'DISPLAY': ':0', 22:46:50 INFO - 'HOME': '/home/cltbld', 22:46:50 INFO - 'LANG': 'en_US.UTF-8', 22:46:50 INFO - 'LANGUAGE': 'en_US:en', 22:46:50 INFO - 'LOGNAME': 'cltbld', 22:46:50 INFO - 'MAIL': '/var/mail/cltbld', 22:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:50 INFO - 'MOZ_NO_REMOTE': '1', 22:46:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:50 INFO - 'NO_EM_RESTART': '1', 22:46:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:46:50 INFO - 'PWD': '/builds/slave/test-pgo', 22:46:50 INFO - 'SHELL': '/bin/bash', 22:46:50 INFO - 'SHLVL': '1', 22:46:50 INFO - 'TERM': 'linux', 22:46:50 INFO - 'TMOUT': '86400', 22:46:50 INFO - 'USER': 'cltbld', 22:46:50 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:50 INFO - '_': '/tools/buildbot/bin/python'} 22:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:50 INFO - Downloading/unpacking psutil>=0.7.1 22:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:52 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:46:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:46:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:46:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:46:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:46:52 INFO - Installing collected packages: psutil 22:46:52 INFO - Running setup.py install for psutil 22:46:52 INFO - building 'psutil._psutil_linux' extension 22:46:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:46:53 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 22:46:54 INFO - building 'psutil._psutil_posix' extension 22:46: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 22:46: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 22:46:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:46:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:46:54 INFO - Successfully installed psutil 22:46:54 INFO - Cleaning up... 22:46:54 INFO - Return code: 0 22:46:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:46: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')]} 22:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46: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')]} 22:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46: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 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:46: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 22:46: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 22:46:54 INFO - Using env: {'DISPLAY': ':0', 22:46:54 INFO - 'HOME': '/home/cltbld', 22:46:54 INFO - 'LANG': 'en_US.UTF-8', 22:46:54 INFO - 'LANGUAGE': 'en_US:en', 22:46:54 INFO - 'LOGNAME': 'cltbld', 22:46:54 INFO - 'MAIL': '/var/mail/cltbld', 22:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:54 INFO - 'MOZ_NO_REMOTE': '1', 22:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:54 INFO - 'NO_EM_RESTART': '1', 22:46:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:46:54 INFO - 'PWD': '/builds/slave/test-pgo', 22:46:54 INFO - 'SHELL': '/bin/bash', 22:46:54 INFO - 'SHLVL': '1', 22:46:54 INFO - 'TERM': 'linux', 22:46:54 INFO - 'TMOUT': '86400', 22:46:54 INFO - 'USER': 'cltbld', 22:46:54 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:54 INFO - '_': '/tools/buildbot/bin/python'} 22:46:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:46:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:46:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:46: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) 22:46:56 INFO - Installing collected packages: mozsystemmonitor 22:46:56 INFO - Running setup.py install for mozsystemmonitor 22:46:56 INFO - Successfully installed mozsystemmonitor 22:46:56 INFO - Cleaning up... 22:46:56 INFO - Return code: 0 22:46:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:46: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')]} 22:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46: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')]} 22:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46: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 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:46: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 22:46: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 22:46:56 INFO - Using env: {'DISPLAY': ':0', 22:46:56 INFO - 'HOME': '/home/cltbld', 22:46:56 INFO - 'LANG': 'en_US.UTF-8', 22:46:56 INFO - 'LANGUAGE': 'en_US:en', 22:46:56 INFO - 'LOGNAME': 'cltbld', 22:46:56 INFO - 'MAIL': '/var/mail/cltbld', 22:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:56 INFO - 'MOZ_NO_REMOTE': '1', 22:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:56 INFO - 'NO_EM_RESTART': '1', 22:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:46:56 INFO - 'PWD': '/builds/slave/test-pgo', 22:46:56 INFO - 'SHELL': '/bin/bash', 22:46:56 INFO - 'SHLVL': '1', 22:46:56 INFO - 'TERM': 'linux', 22:46:56 INFO - 'TMOUT': '86400', 22:46:56 INFO - 'USER': 'cltbld', 22:46:56 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:56 INFO - '_': '/tools/buildbot/bin/python'} 22:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:56 INFO - Downloading/unpacking blobuploader==1.2.4 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46:58 INFO - Downloading blobuploader-1.2.4.tar.gz 22:46:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:46:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:46:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:46:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:46:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46:58 INFO - Downloading docopt-0.6.1.tar.gz 22:46:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:46:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:46:59 INFO - Installing collected packages: blobuploader, requests, docopt 22:46:59 INFO - Running setup.py install for blobuploader 22:46:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:46:59 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:46:59 INFO - Running setup.py install for requests 22:46:59 INFO - Running setup.py install for docopt 22:46:59 INFO - Successfully installed blobuploader requests docopt 22:46:59 INFO - Cleaning up... 22:46:59 INFO - Return code: 0 22:46:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:46: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')]} 22:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46: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')]} 22:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46: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 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:46: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 22:46: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 22:46:59 INFO - Using env: {'DISPLAY': ':0', 22:46:59 INFO - 'HOME': '/home/cltbld', 22:46:59 INFO - 'LANG': 'en_US.UTF-8', 22:46:59 INFO - 'LANGUAGE': 'en_US:en', 22:46:59 INFO - 'LOGNAME': 'cltbld', 22:46:59 INFO - 'MAIL': '/var/mail/cltbld', 22:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:59 INFO - 'MOZ_NO_REMOTE': '1', 22:46:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:59 INFO - 'NO_EM_RESTART': '1', 22:46:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:46:59 INFO - 'PWD': '/builds/slave/test-pgo', 22:46:59 INFO - 'SHELL': '/bin/bash', 22:46:59 INFO - 'SHLVL': '1', 22:46:59 INFO - 'TERM': 'linux', 22:46:59 INFO - 'TMOUT': '86400', 22:46:59 INFO - 'USER': 'cltbld', 22:46:59 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:59 INFO - '_': '/tools/buildbot/bin/python'} 22:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:46:59 INFO - Running setup.py (path:/tmp/pip-HSRfPP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:46:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:46:59 INFO - Running setup.py (path:/tmp/pip-LdOGjb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:47:00 INFO - Running setup.py (path:/tmp/pip-WDzOFj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:47:00 INFO - Running setup.py (path:/tmp/pip-9agdjr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:47:00 INFO - Running setup.py (path:/tmp/pip-gouFb0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:47:00 INFO - Running setup.py (path:/tmp/pip-kMZc6J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:47:00 INFO - Running setup.py (path:/tmp/pip-m0XW0Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:47:00 INFO - Running setup.py (path:/tmp/pip-MqiMxQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:47:00 INFO - Running setup.py (path:/tmp/pip-N9TaA5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:47:00 INFO - Running setup.py (path:/tmp/pip-DfBABa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:47:00 INFO - Running setup.py (path:/tmp/pip-9FKGzU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:47:00 INFO - Running setup.py (path:/tmp/pip-fJtKTv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:47:00 INFO - Running setup.py (path:/tmp/pip-OFcXlp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:47:00 INFO - Running setup.py (path:/tmp/pip-HSgAST-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:47:00 INFO - Running setup.py (path:/tmp/pip-E5tKFT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:47:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:47:00 INFO - Running setup.py (path:/tmp/pip-TIkseJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:47:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:47:01 INFO - Running setup.py (path:/tmp/pip-T32J5i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:47:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:47:01 INFO - Running setup.py install for manifestparser 22:47:01 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Running setup.py install for mozcrash 22:47:01 INFO - Running setup.py install for mozdebug 22:47:01 INFO - Running setup.py install for mozdevice 22:47:01 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Running setup.py install for mozfile 22:47:01 INFO - Running setup.py install for mozhttpd 22:47:01 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Running setup.py install for mozinfo 22:47:01 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Running setup.py install for mozInstall 22:47:01 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:47:01 INFO - Running setup.py install for mozleak 22:47:01 INFO - Running setup.py install for mozlog 22:47:02 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:47:02 INFO - Running setup.py install for moznetwork 22:47:02 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:47:02 INFO - Running setup.py install for mozprocess 22:47:02 INFO - Running setup.py install for mozprofile 22:47:02 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:47:02 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:47:02 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:47:02 INFO - Running setup.py install for mozrunner 22:47:02 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:47:02 INFO - Running setup.py install for mozscreenshot 22:47:02 INFO - Running setup.py install for moztest 22:47:02 INFO - Running setup.py install for mozversion 22:47:02 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:47:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:47:02 INFO - Cleaning up... 22:47:02 INFO - Return code: 0 22:47:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:47:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 22:47:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/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 22:47:02 INFO - Using env: {'DISPLAY': ':0', 22:47:02 INFO - 'HOME': '/home/cltbld', 22:47:02 INFO - 'LANG': 'en_US.UTF-8', 22:47:02 INFO - 'LANGUAGE': 'en_US:en', 22:47:02 INFO - 'LOGNAME': 'cltbld', 22:47:02 INFO - 'MAIL': '/var/mail/cltbld', 22:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:02 INFO - 'MOZ_NO_REMOTE': '1', 22:47:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:02 INFO - 'NO_EM_RESTART': '1', 22:47:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:02 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:02 INFO - 'SHELL': '/bin/bash', 22:47:02 INFO - 'SHLVL': '1', 22:47:02 INFO - 'TERM': 'linux', 22:47:02 INFO - 'TMOUT': '86400', 22:47:02 INFO - 'USER': 'cltbld', 22:47:02 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:02 INFO - '_': '/tools/buildbot/bin/python'} 22:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:47:03 INFO - Running setup.py (path:/tmp/pip-9ctrCG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:47:03 INFO - Running setup.py (path:/tmp/pip-61OzED-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:47:03 INFO - Running setup.py (path:/tmp/pip-916edA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:47:03 INFO - Running setup.py (path:/tmp/pip-X2SfmK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:47:03 INFO - Running setup.py (path:/tmp/pip-P4FWcy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:47:03 INFO - Running setup.py (path:/tmp/pip-fB8rwu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:47:03 INFO - Running setup.py (path:/tmp/pip-42555H-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:47:03 INFO - Running setup.py (path:/tmp/pip-LgGAMz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:47:03 INFO - Running setup.py (path:/tmp/pip-JSSgj7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:47: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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:47:03 INFO - Running setup.py (path:/tmp/pip-bkRw22-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:47:03 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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:47:03 INFO - Running setup.py (path:/tmp/pip-jCSkh4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:47:03 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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:47:03 INFO - Running setup.py (path:/tmp/pip-Qyz__s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:47:03 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)) 22:47:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:47:03 INFO - Running setup.py (path:/tmp/pip-3LXwbY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:47: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)) 22:47:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:47:04 INFO - Running setup.py (path:/tmp/pip-FA5IHx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:47: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)) 22:47:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:47:04 INFO - Running setup.py (path:/tmp/pip-OSY1ra-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:47: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)) 22:47:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:47:04 INFO - Running setup.py (path:/tmp/pip-VcKEFn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:47: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)) 22:47:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:47:04 INFO - Running setup.py (path:/tmp/pip-xa2_kB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47:05 INFO - Downloading blessings-1.5.1.tar.gz 22:47:05 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 22:47:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:47:06 INFO - Installing collected packages: blessings 22:47:06 INFO - Running setup.py install for blessings 22:47:06 INFO - Successfully installed blessings 22:47:06 INFO - Cleaning up... 22:47:06 INFO - Return code: 0 22:47:06 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:47:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:47:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:47:06 INFO - Reading from file tmpfile_stdout 22:47:06 INFO - Current package versions: 22:47:06 INFO - argparse == 1.2.1 22:47:06 INFO - blessings == 1.5.1 22:47:06 INFO - blobuploader == 1.2.4 22:47:06 INFO - docopt == 0.6.1 22:47:06 INFO - manifestparser == 1.1 22:47:06 INFO - mozInstall == 1.12 22:47:06 INFO - mozcrash == 0.16 22:47:06 INFO - mozdebug == 0.1 22:47:06 INFO - mozdevice == 0.47 22:47:06 INFO - mozfile == 1.2 22:47:06 INFO - mozhttpd == 0.7 22:47:06 INFO - mozinfo == 0.9 22:47:06 INFO - mozleak == 0.1 22:47:06 INFO - mozlog == 3.1 22:47:06 INFO - moznetwork == 0.27 22:47:06 INFO - mozprocess == 0.22 22:47:06 INFO - mozprofile == 0.28 22:47:06 INFO - mozrunner == 6.11 22:47:06 INFO - mozscreenshot == 0.1 22:47:06 INFO - mozsystemmonitor == 0.0 22:47:06 INFO - moztest == 0.7 22:47:06 INFO - mozversion == 1.4 22:47:06 INFO - psutil == 3.1.1 22:47:06 INFO - requests == 1.2.3 22:47:06 INFO - wsgiref == 0.1.2 22:47:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:47: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')]} 22:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47: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')]} 22:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47: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 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:47: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 22:47: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 22:47:06 INFO - Using env: {'DISPLAY': ':0', 22:47:06 INFO - 'HOME': '/home/cltbld', 22:47:06 INFO - 'LANG': 'en_US.UTF-8', 22:47:06 INFO - 'LANGUAGE': 'en_US:en', 22:47:06 INFO - 'LOGNAME': 'cltbld', 22:47:06 INFO - 'MAIL': '/var/mail/cltbld', 22:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:06 INFO - 'MOZ_NO_REMOTE': '1', 22:47:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:06 INFO - 'NO_EM_RESTART': '1', 22:47:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:06 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:06 INFO - 'SHELL': '/bin/bash', 22:47:06 INFO - 'SHLVL': '1', 22:47:06 INFO - 'TERM': 'linux', 22:47:06 INFO - 'TMOUT': '86400', 22:47:06 INFO - 'USER': 'cltbld', 22:47:06 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:06 INFO - '_': '/tools/buildbot/bin/python'} 22:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47: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)) 22:47:06 INFO - Cleaning up... 22:47:06 INFO - Return code: 0 22:47:06 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:47: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')]} 22:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47: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')]} 22:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47: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 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:47: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 22:47: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 22:47:06 INFO - Using env: {'DISPLAY': ':0', 22:47:06 INFO - 'HOME': '/home/cltbld', 22:47:06 INFO - 'LANG': 'en_US.UTF-8', 22:47:06 INFO - 'LANGUAGE': 'en_US:en', 22:47:06 INFO - 'LOGNAME': 'cltbld', 22:47:06 INFO - 'MAIL': '/var/mail/cltbld', 22:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:06 INFO - 'MOZ_NO_REMOTE': '1', 22:47:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:06 INFO - 'NO_EM_RESTART': '1', 22:47:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:06 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:06 INFO - 'SHELL': '/bin/bash', 22:47:06 INFO - 'SHLVL': '1', 22:47:06 INFO - 'TERM': 'linux', 22:47:06 INFO - 'TMOUT': '86400', 22:47:06 INFO - 'USER': 'cltbld', 22:47:06 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:06 INFO - '_': '/tools/buildbot/bin/python'} 22:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:06 INFO - Downloading/unpacking jsonschema 22:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:47: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 22:47:08 INFO - Downloading/unpacking functools32 (from jsonschema) 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47:08 INFO - Downloading functools32-3.2.3-2.tar.gz 22:47: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 22:47:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:47:08 INFO - warning: no files found matching '*.txt' 22:47:08 INFO - no previously-included directories found matching 'build' 22:47:08 INFO - no previously-included directories found matching 'dist' 22:47:08 INFO - no previously-included directories found matching '.git*' 22:47:08 INFO - Installing collected packages: jsonschema, functools32 22:47:08 INFO - Running setup.py install for functools32 22:47:08 INFO - warning: no files found matching '*.txt' 22:47:08 INFO - no previously-included directories found matching 'build' 22:47:08 INFO - no previously-included directories found matching 'dist' 22:47:08 INFO - no previously-included directories found matching '.git*' 22:47:09 INFO - Successfully installed jsonschema functools32 22:47:09 INFO - Cleaning up... 22:47:09 INFO - Return code: 0 22:47:09 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:47: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')]} 22:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47: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')]} 22:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47: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 0x7f682b56cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e198a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ee9630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, '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': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', '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 22:47: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 22:47: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 22:47:09 INFO - Using env: {'DISPLAY': ':0', 22:47:09 INFO - 'HOME': '/home/cltbld', 22:47:09 INFO - 'LANG': 'en_US.UTF-8', 22:47:09 INFO - 'LANGUAGE': 'en_US:en', 22:47:09 INFO - 'LOGNAME': 'cltbld', 22:47:09 INFO - 'MAIL': '/var/mail/cltbld', 22:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:09 INFO - 'MOZ_NO_REMOTE': '1', 22:47:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:09 INFO - 'NO_EM_RESTART': '1', 22:47:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:09 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:09 INFO - 'SHELL': '/bin/bash', 22:47:09 INFO - 'SHLVL': '1', 22:47:09 INFO - 'TERM': 'linux', 22:47:09 INFO - 'TMOUT': '86400', 22:47:09 INFO - 'USER': 'cltbld', 22:47:09 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:09 INFO - '_': '/tools/buildbot/bin/python'} 22:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:09 INFO - Downloading/unpacking flake8 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:47:10 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 22:47:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:47: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 22:47:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47: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 22:47:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 22:47:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:47:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:47:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:47:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:47:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:47:11 INFO - no previously-included directories found matching 'docs/_build' 22:47:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:47:11 INFO - Running setup.py install for pep8 22:47:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:47:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:47:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:47:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:47:11 INFO - no previously-included directories found matching 'docs/_build' 22:47:11 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:47:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:47:11 INFO - Cleaning up... 22:47:11 INFO - Return code: 0 22:47:11 INFO - Running post-action listener: _resource_record_post_action 22:47:11 INFO - Running post-action listener: _start_resource_monitoring 22:47:11 INFO - Starting resource monitoring. 22:47:11 INFO - ##### 22:47:11 INFO - ##### Running install step. 22:47:11 INFO - ##### 22:47:11 INFO - Running pre-action listener: _resource_record_pre_action 22:47:11 INFO - Running main action method: install 22:47:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:47:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:47:12 INFO - Reading from file tmpfile_stdout 22:47:12 INFO - Detecting whether we're running mozinstall >=1.0... 22:47:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:47:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:47:12 INFO - Reading from file tmpfile_stdout 22:47:12 INFO - Output received: 22:47:12 INFO - Usage: mozinstall [options] installer 22:47:12 INFO - Options: 22:47:12 INFO - -h, --help show this help message and exit 22:47:12 INFO - -d DEST, --destination=DEST 22:47:12 INFO - Directory to install application into. [default: 22:47:12 INFO - "/builds/slave/test-pgo"] 22:47:12 INFO - --app=APP Application being installed. [default: firefox] 22:47:12 INFO - mkdir: /builds/slave/test-pgo/build/application 22:47: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'] 22:47: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 22:47:27 INFO - Reading from file tmpfile_stdout 22:47:27 INFO - Output received: 22:47:27 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:47:27 INFO - Running post-action listener: _resource_record_post_action 22:47:27 INFO - ##### 22:47:27 INFO - ##### Running run-tests step. 22:47:27 INFO - ##### 22:47:27 INFO - Running pre-action listener: _resource_record_pre_action 22:47:27 INFO - Running main action method: run_tests 22:47:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:47:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:47:27 INFO - Python 2.7.3 22:47:27 INFO - Return code: 0 22:47:27 INFO - grabbing minidump binary from tooltool 22:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:47:27 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 0x1f7a840>, '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 0x1ee76b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ee7b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:47:27 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 22:47:27 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 22:47:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:47:28 INFO - Return code: 0 22:47:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:47:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:47:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:47:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:47:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:47:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:47:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:47:29 INFO - Return code: 0 22:47: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/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 22:47: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/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 22:47:29 INFO - Using env: {'DISPLAY': ':0', 22:47:29 INFO - 'HOME': '/home/cltbld', 22:47:29 INFO - 'LANG': 'en_US.UTF-8', 22:47:29 INFO - 'LANGUAGE': 'en_US:en', 22:47:29 INFO - 'LOGNAME': 'cltbld', 22:47:29 INFO - 'MAIL': '/var/mail/cltbld', 22:47:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:47:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:29 INFO - 'MOZ_NO_REMOTE': '1', 22:47:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:47:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:29 INFO - 'NO_EM_RESTART': '1', 22:47:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:29 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:47:29 INFO - 'SHELL': '/bin/bash', 22:47:29 INFO - 'SHLVL': '1', 22:47:29 INFO - 'TERM': 'linux', 22:47:29 INFO - 'TMOUT': '86400', 22:47:29 INFO - 'USER': 'cltbld', 22:47:29 INFO - 'XDG_SESSION_COOKIE': '21869f6c619b95355032545b000001da-1451542489.989706-1542306809', 22:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:29 INFO - '_': '/tools/buildbot/bin/python'} 22:47: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/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 22:47:29 INFO - mozversion application_buildid: 20151230203059 22:47:29 INFO - mozversion application_changeset: 782774a4dd2d6d659f31b47b2968d4464cee7202 22:47:29 INFO - mozversion application_display_name: Nightly 22:47:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:47:29 INFO - mozversion application_name: Firefox 22:47:29 INFO - mozversion application_remotingname: firefox 22:47:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/b2g-inbound 22:47:29 INFO - mozversion application_vendor: Mozilla 22:47:29 INFO - mozversion application_version: 46.0a1 22:47:29 INFO - mozversion platform_buildid: 20151230203059 22:47:29 INFO - mozversion platform_changeset: 782774a4dd2d6d659f31b47b2968d4464cee7202 22:47:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/b2g-inbound 22:47:29 INFO - mozversion platform_version: 46.0a1 22:47:29 INFO - using testdate: 1451544449 22:47:29 INFO - actual date: 1451544449 22:47:29 INFO - starting webserver on 'localhost:37276' 22:47:29 INFO - SUITE-START | Running 3 tests 22:47:29 INFO - TEST-START | dromaeo_css 22:47:29 INFO - operating with platform_type : linux_ 22:47:29 INFO - Initialising browser for dromaeo_css test... 22:47:29 INFO - TEST-INFO | started process 3315 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsRGAaS/profile http://localhost:37276/getInfo.html) 22:47:36 INFO - PROCESS | 3315 | __metrics Screen width/height:1600/1200 22:47:36 INFO - PROCESS | 3315 | colorDepth:24 22:47:36 INFO - PROCESS | 3315 | Browser inner width/height: 1024/697 22:47:36 INFO - PROCESS | 3315 | __metrics 22:47:36 INFO - PROCESS | 3315 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:47:36 INFO - PROCESS | 3315 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:47:37 INFO - TEST-INFO | 3315: exit 0 22:47:37 INFO - Browser initialized. 22:47:37 INFO - Running cycle 1/1 for dromaeo_css test... 22:47:37 INFO - TEST-INFO | started process 3413 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpsRGAaS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:47:48 INFO - PROCESS | 3413 | RSS: Main: 147890176 22:47:48 INFO - PROCESS | 3413 | 22:48:39 INFO - PROCESS | 3413 | 1451544519677 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] 22:50:49 INFO - PROCESS | 3413 | Cycle 1(1): loaded http://localhost:37276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37276/tests/dromaeo/cssquery-ext.html) 22:50:50 INFO - PROCESS | 3413 | RSS: Main: 327184384 22:50:50 INFO - PROCESS | 3413 | 22:53:53 INFO - PROCESS | 3413 | Cycle 1(1): loaded http://localhost:37276/tests/dromaeo/cssquery-ext.html (next: http://localhost:37276/tests/dromaeo/cssquery-jquery.html) 22:53:53 INFO - PROCESS | 3413 | RSS: Main: 241913856 22:53:53 INFO - PROCESS | 3413 | 22:56:55 INFO - PROCESS | 3413 | Cycle 1(1): loaded http://localhost:37276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37276/tests/dromaeo/cssquery-mootools.html) 22:56:56 INFO - PROCESS | 3413 | RSS: Main: 238960640 22:56:56 INFO - PROCESS | 3413 | 22:59:58 INFO - PROCESS | 3413 | Cycle 1(1): loaded http://localhost:37276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37276/tests/dromaeo/cssquery-prototype.html) 22:59:58 INFO - PROCESS | 3413 | RSS: Main: 218484736 22:59:58 INFO - PROCESS | 3413 | 23:03:01 INFO - PROCESS | 3413 | Cycle 1(1): loaded http://localhost:37276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37276/tests/dromaeo/cssquery-yui.html) 23:03:01 INFO - PROCESS | 3413 | RSS: Main: 219176960 23:03:01 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | Cycle 1(1): loaded http://localhost:37276/tests/dromaeo/cssquery-yui.html (next: http://localhost:37276/tests/dromaeo/cssquery-dojo.html) 23:06:04 INFO - PROCESS | 3413 | RSS: Main: 279277568 23:06:04 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | __start_tp_report 23:06:04 INFO - PROCESS | 3413 | _x_x_mozilla_page_load 23:06:04 INFO - PROCESS | 3413 | _x_x_mozilla_page_load_details 23:06:04 INFO - PROCESS | 3413 | |i|pagename|runs| 23:06:04 INFO - PROCESS | 3413 | |0;dojo.html;3198.2164369729417;3232.4181647303476;3261.527078573607;3267.722243609293;3292.7859689120214;5168.431472538018;5305.098133317336;5379.300690910181;5400.918986215205;5458.999999999995;5329.360476742787;5339.6262261641705;5348.037353276409;5394.946050539494;5495.752691128899;4838.782254798533;4903.289023091667;4918.336024636664;4976.875578110558;4986.127427700156;4912.631552633549;4974.925376119361;5009.799608015675;5014.5737612303;5072.213806859924;4836.951630483721;4973.62697797663;4998.450170481244;5070.2141168119;5085.415177254607;4814.903701925977;4896.804127834882;4923.507649235084;4932.975335123301;4936.111499930011;4939.777710003059;4943.480934501861;4943.901121977577;4960.677555958851;4980.7011579305;5222.895542089175;5268.6575372600655;5276.2085687146855;5280.18157185633;5293.497117773805;5711.514521265656;5767.192593036978;5838.065909454509;5840.123981402781;5841.123831425278;5695.971520142414;5770.278715160605;5789.015867302526;5789.8263052108505;5792.82621521355;5403.945960540423;5404.864878377994;5405.945940540583;5413.945860541384;5414.296141501619;5765.221695071195;5826.329972053268;5874.500667443315;5929.6145750526075;5949;5669.43305669438;5670.8015219467125;5673.546116310686;5674.148877668383;5693.544516438675;5721.427857214246;5744.942550574441;5761.88476230473;5773.538116950634;5828.358880523139;5680.176374425685;5700.543956483554;5766.365699773021;5794.768209271666;5804.564657650693;5773.740181691834;5782.219400380979;5787.218725472091;5790.392008839095;5811.099279611667;4443.289073748185;4443.755593442327;4448.021435284232;4454.042380888124;4492.011757413364;3942.743721658083;3951.7628942263555;3975.2372101389833;4001.599840015975;4027.7180597357906;5288.497592728685;5316.122839731478;5322.690606167648;5323.840284791462;5332.226827110047;5717.714114294268;5720.742566584514;5722.799702010409;5725.2843394575675;5770.221020162308;5279.44565820591;5305.734713264322;5381.677099373972;5382.3541175059245;5422.864428389243;4863.878403039882;4881.341018962395;4886.047220791911;4892.046051020088;4927.433345165352;5954.37479064692;5959.523238140939;5962.07587823888;5963.135345374896;5964.015937370372;5541.778328866962;5577.637453565505;5706.457886500942;5727.570432217517;5729.026065568946;5888.970555147368;5903.557233207613;5905.616134951214;5906.40935906406;5906.911396329145;5644.689542075225;5655.547556195412;5656.264685590847;5660.943390566042;5662.745176466987;5670.234518339971;5779.7110144493445;5791.218185544897;5798.59409841307;5805.970970145122;5581.330240371181;5634.718264086861;5669.801556945487;5675.8864822704145;5680.68756218412;5636.915446268391;5650.29371328584;5670.262865827416;5670.347909990239;5671.035923892866;5508.944910550843;5511.779528818963;5513.972430137824;5531.004419204582;5552.694601796778;5596.216529685928;5613.158026295925;5627.465390787868;5647.5199608032135;5694.4590263924865;5477.123660214506;5524.861878453071;5527.309086364205;5591.384947655673;5600.243967064557 23:06:04 INFO - PROCESS | 3413 | |1;ext.html;528.9869899143141;540.4946375139245;545.2176127257384;552.9060059789837;563.4590792838875;30058.549121763164;31026.37947241057;36798.08004799881;37184.814075929586;37228;38761;39311.803440982825;39437.40843887344;39469.605303946955;39491.60508394915;7295.963520182391;8396.748097557083;8565.100664430238;8568.957155214215;8570.485770853738;7385.815354616124;7481.289277518627;7490.3633191178815;10014.048665376815;10190;9939.40363578184;10044.447555384451;10051.597936082548;10056.648017319396;10062.19502439803;9841.212702983745;9905.20758339331;9935.453550054744;9939.5030248488;9940.105390514816;7415.480916335859;7602.809929751746;7605.049368828896;9973.80052398948;10199.847002294971;39317.803410982764;40080.59919400798;40101.79949100265;40122.19755604872;40182.59817401818;3191.1702957231287;3248.3503299340227;3561.5370001899846;3864.942025869614;4048.332025215836;30167.396652066836;30206.395872082434;30335.393292134035;30835.537466938014;31510;3823.836775317243;3850.5764365919727;3899.473571067926;3907.355286377744;3988.720789544733;274475;277088;277699;278842.6057869718;278969;51781;59741.103883441785;59797.40202598005;60267.69866150641;60313.39686603165;52513;52531.47468525266;65427;65960.67019664968;66024;13929;14120.364583593762;14263.500777472738;14281.071730337495;14291.213983230815;10627.256092073481;10702.09032232269;10706.1435085193;10712.46437678113;10722.678319650424;19313.009789958855;20099.598008039757;20299.69550456715;20353.898230508752;20360.490987725425;7444.367228785614;7597.392208623297;7620;7621.199774023758;7674.577898215652;952.1430712358932;978.6672531339378;985.5614251657944;1075.876274228458;1146.2434793036555;28418.573721393783;28912.855435722686;29464.852675736485;29569.408611827643;29581.55627665587;998.960041598328;1016.9186465082776;1023.3297190340339;1026.9383836969805;1123.9831402528969;13364.933175334061;13431.069522187989;13592;13725.862741372659;13828.239446830521;338039;342089;342635;343144;343539;51784.48215517797;52093.218601721004;52223.216651750256;52243;52268.47731522712;1303.1724854717322;1305.321232958875;1320.7490576790378;1350.581319790868;1352.4319785689836;24382.75617243805;24492.510149796548;25638.87180564085;25683.486330272917;25756.61365079563;44311.55688443074;44688.553114468436;44785.776071119435;44831.32753008642;44869;208877;209765;209817;209845;210441;29077.41845163127;29284.56073158947;29662.258443539085;29701.702982969895;29706.55440168356;39397.80301098476;39845.41777695602;43235.783821080695;43819.342709860015;44292;36838.8158059208;36865.63134368622;37708;38084.428733568464;38538.229235414576;30193.396132077676;34726.47910281297;36850.26299474049;37637.623623764506;37827.810860945516;32810;33172.1706957328;33565.83217083899;33576.49635255522;33608.495872562424;22939.311820645406;24273.150439734523;24609.507809843344;24613.13854015101;25074.430364596814;22816.885915570318;22865.19971800979;23155.3053408398;24008.63987040231;24193.637095443934 23:06:04 INFO - PROCESS | 3413 | |2;jquery.html;347.2222222222222;347.8191340502938;350.5950627025786;355.04847010013157;356.5080189338712;16129.919350403261;16762.83237167628;16849;17285.48143555692;17348.132593370345;10603.575856965712;11088.2238243323;16726.58183545412;16990;17012.319507219698;4903.043906438254;5002.974985125069;5015;5016.774245158977;5020.422651395082;4974.253861920704;5066.847994560169;5276.709780962045;5279.894402111955;5283.735813209324;3723.864221412468;3841.308564458396;5234.450382709818;5239.083160446907;5283.788648454057;5373.623846330739;5376.865578360573;5414.214938833847;5428.83713488596;5433.1035379162395;5217.8173763918085;5223.843284701465;6051.878962420727;6248.96875515624;6258.280297765769;14273.072250303698;14280.428782848674;17513.47459576215;18514;21319.614225075322;2161.8810965396897;2173.673948907661;2192.6710993350966;2212.6570381590877;2219.678146668724;12176.452059657247;14297;14323.641908952255;14753.967222294445;15210.391584336614;1926.1139875657213;1929.1222493765304;1947.2892394275987;1956.2370675436591;1967.9704804427945;58711.41288587145;59447.40552594462;60145.39854601442;61791;69509.65245173793;9617.182539484222;9798.31411801167;10081.848772268422;10083.49582520871;14634.975551711492;12025.759484810254;12037.157398982163;12130.757384852253;12205.755884882252;12287;6659.367360100783;6800.31996800316;6826.146731658543;6852.451803855779;6868.759593414206;6608.702608382634;6624.304448032887;6675.899861502081;6722.66386680664;6723.462123030145;4007.0383108053857;4007.0583412897945;4022.8189731462153;4428.627125591012;4507.414036175276;1821.8451431628196;1900.439370385712;1901.4580844459263;1926.373928473252;1958.9706154407695;824.583585289425;917.3349321741817;931.0968360690119;1005.939643621385;1060.522764755863;13732.366223074796;15228.619284517754;15941;15964.121973291349;16340.428085016967;893.5934149961752;911.7401540560908;939.736873675372;941.1764705882352;957.5308099031424;8346.499210047417;8847.955760221157;9336.906630933603;9399.90600094004;10133.746656333504;543493;570969;572325.4907058302;572385;582749;77405.61297193478;79436.6028169867;80994.5950270245;81966;82731;1146.3465824480134;1158.3339579741514;1162.84301619282;1166.585862018988;1169.2984209474419;15386.69226615439;15855.445059422866;15867.365305387655;15988.440404585785;16183.867129300836;33541.496877547346;33596.160095997795;34748;34793.13017174591;35623.82188089043;39981.40027899526;40202.798986004884;40448.3932741015;40643.187136256514;40881.79559102185;9400.91889432724;9466.242700584075;9475.24198064167;9540.427574345562;9546.761330966503;16534.189187838336;16550;16672.83327166713;16969.066701331052;17202.13989300555;14161.50056425266;14681.119132852064;14730.77903831422;15077.924610376878;15130.394784208509;11014.859435766395;13466.326683665973;13673.453061877812;13705.451781928612;14019.50931717385;13684.247366394944;14042.929785351416;14109.153450792985;14346.641333966734;14369.64075898111;9466.574004169966;9652.324337296322;9753.756156096155;9782.902170978485;9786.266030047633;11731.765364692828;13839.446422143;13946.58160255194;14827.92586037106;15233.847661523687 23:06:04 INFO - PROCESS | 3413 | |3;mootools.html;1260.6281147061616;1395.5324966136347;1405.4588983241451;1517.962050948726;1753.5353131420177;6793.116894803675;7151.678174482147;7397.889031664523;7447.553146811181;7485;7337.19290878003;7508.737194198204;7524.96237518813;7545.698172073125;7549.131849837257;2255.2783109404995;2276.658501224821;2284.246198754407;2292.805111565519;2293.2317673579287;2257.5146343536135;2286.6570014497793;2312.861228326306;2314.155333303349;2323;2177.5862586108587;2178.705874706918;2186.606410846047;2205.647096464566;2212.83403744719;2188.0481990334256;2190.463336482564;2219.079082180901;2220.378294077661;2222.2666520048424;2104.2214380679093;2131.997960958347;2132.648113061343;2132.776058513865;2133.626615342309;7419.443541734391;7578.583177925212;7586.431017673642;7589.468737188399;7604.125525564576;4523.253663145577;4610.9077818443775;4620.491745907947;4629.606483448911;4637.930431043537;2913.257119434552;3007.8947236846825;3007.9699203008126;3035.362573859492;3094.5512900629506;961.6634178037701;985.5663508056432;987.4026214140486;1003.3829195045067;1011.8836333821631;10430.739231519197;10479.21405894561;10500.78998420035;10502.68491945243;10503.52734126966;4060.715749897538;4102.343625019982;4143.08852052548;4154.169166166779;4224.028473451123;8936;8951.597178127;8952.015278319379;8976.14726600985;8988.59551320192;4612.400387949545;4638;4641.419822522184;4642.837500687459;4647;3663.725220608465;3733.6266373362446;4124.463819703419;4229.984803647102;4239.639630631431;3567.4648802679285;3584.372734771405;3600.0999750062483;3617.764845285101;3627.510286111394;1132.7904337697553;1144.1190283481671;1180.221054104287;1183.2782002978176;1186.2526608236756;1015.3704703083987;1021.0249212002519;1213.8725433829497;1281.6731733407921;1301.8958483321312;7157.2127066022695;7175.928240717631;7176.138863336433;7255.383292420098;7289.015982842355;491.9458859525521;494.56972433981946;494.92328689053255;495.28184133007284;496.31260703925324;5731.824975879935;5792.681402522901;5793.24687790593;5824.621399609096;5974.522038237017;9046.909530904739;9058.54707264634;9070.727878163665;9082.86375704365;9261.67584134552;8582.270507006975;8745.868811967826;8893.333000024895;8974.102589740975;8999.505027223566;7076.823079423056;7164.391026762679;7171.03191069199;7176.712931482786;7179.066721326299;7058.400035996998;7084.858302834017;7113.57318560888;7137.571745695067;7209.963950180216;8022.638981245741;8122.634481448231;8170.01959764832;8327.08477050294;8345.123762004901;8292.253697167283;8479.745607631781;8485.57572121379;8500.574971251537;8645.481271123752;6186.721597528032;6307.842303942254;6312.652804095819;6773.661316934035;6927.58434493932;6830.043793868962;6853;6997.580145191305;7008.684609192496;7025.754098606524;6363.649999250086;6614.206295244601;6647.534672572873;6708.966455167693;6791.558548694319;6731;6797.4901882358035;6801.18385793708;6817.454603631599;6861.965690171517;6710.328967103251;6774.153230846145;6809.5233333666165;6814.1482314710665;6815.318468153145;6029.216201893902;6051.697415129314;6053.60651557665;6066.757329706762;6075.362086980804;6228.968855155696;6248.4064013918605;6255.437010669061;6260.28006779208;6261.780837670842 23:06:04 INFO - PROCESS | 3413 | |4;prototype.html;336.2065525360148;336.69529076186046;337.8749862550857;344.22892720306515;344.7431663410758;5843.590948633598;5970.6417614943075;5985.34161242263;5993.400659934005;6027.065804800252;5814.534837213024;5934.554908381878;5978;5981.252343457068;5991.640501569909;4631.722096674193;4639.675222734419;4645.9767701161445;4649.93025104622;4652.74409907455;4435.600795928366;4522.2312206924735;4535.750533720644;4636.51316611756;4638.814447422099;4559.772011399434;4568.703034302776;4577.267637178053;4590.059037897223;4606.700564463316;4570.245909424941;4588.26587745959;4625.444946606395;4629.560191781776;4650.697704649221;4065.6340929316207;4068.0643452005907;4069.613386728256;4081.653059489947;4090.079732060263;5580.405956143991;5645.350784659776;5664.6601203927485;5699.173619825144;5706;5392.164214546712;5401.756920938567;5407.242985981966;5408.378036525811;5415.10650742627;6101.237345331833;6326.240851097852;6327.335629758855;6331.651759153245;6334.936650633481;4364.694471387004;4386.188555117282;4475.10497900418;4513.119941611387;4513.638908887281;6559.7704080357435;6584.176977877765;6596.472282217459;6600.93399066008;6616.702248398834;4612.953870461252;4615.630749540029;4616.676832621684;4617.168909596237;4623.052274283764;4404.911901761947;4547.704399214041;4569.2689169732685;4676.275177347517;4689.249720044844;5259.447757985433;5290.497402746733;5313.0967735485065;5317.867053323621;5318.25544423781;6424.100625912376;6432.678366081677;6436.388543088399;6438.034294855809;6439.484841212692;1602.8557429831371;1626.4632671218706;1628.0882705684855;1628.3974929276244;1628.6498402843445;1078.5200585739428;1081.1026847716375;1087.8096333141668;1100.862392200975;1107.0755918807886;6058.242719660043;6069.817905462844;6072.878542429126;6076.513878889678;6087.726052327655;6574.934250657528;6588.967055164693;6593.901091483632;6599.340065993459;6605;5929.436703513159;6057.151998720183;6071.848203794941;6072.817815465543;6077.696115194222;3531.964680353215;3567.0725611341236;3567.3935430976276;3568.750187486903;3572.5355703758346;6837.931620683829;6842.794716158523;6843.076184715;6844.2129155147795;6854;6692.531522793455;6697.397234248941;6700.430463410567;6711.597304161766;6717.227518835299;6666.600003999775;6868.587884726932;6871.244163142051;6923.099997000358;6927.341902519353;6362.491000720024;6481.092647029325;6504.317046710027;6598.934010659832;6615.636140012345;6443.838904027437;6544.901826472702;6553.5084868635995;6594.439472644687;6596.208454985433;6786.185657721105;6801.353871382211;6802.625855577793;6806.965965170142;6810.523263371517;4451.844185453623;4566.4748553916725;4605.677602567788;4610.953890461053;4623.398958135421;6507.609543427409;6526.216853977553;6555.967220163869;6607.3062328456745;6620.404163625297;6415.518836087219;6425.518086143651;6431.035344698333;6432.09950606906;6439.484841212786;6239.625622462667;6407.135036769976;6424.582402143846;6427.55007149495;6465.191851018622;6357.078223657451;6361.363863613787;6362.618242905441;6363.459105976044;6369.044643303541;6499.1876015498065;6504.1869766279215;6514.283428822731;6522.543421960418;6526.934730652632;6180.165677633642;6287.96248618991;6306.306306306211;6333.936660633335;6334.081558174131 23:06:04 INFO - PROCESS | 3413 | |5;yui.html;680.5542369747814;702.7399862051042;746.0078096132145;769.1885061260368;769.3076231391747;13030.804537931926;13271.270080145589;13313.201207927532;13404.128731632438;13406.06157568968;11686.240394374381;11687.649370518877;11699.064074874012;11737.706557336049;11812.704682382944;671.0069097735354;678.5792808458763;807.858624740671;819.1194465949128;825.5418242875202;826.9503829770233;830.3066939105854;836.0427310729242;836.2097817562405;836.3685417509802;853.8121613245077;861.3539845116163;868.3183700794877;870.6778491957984;872.3936863879607;700.3871612339203;708.4367927497626;856.2465030772911;866.7053201911381;867.0245973280059;629.3014753623474;635.6694518850172;637.3053371824733;637.6588525139028;638.3743930947651;12723;12796.872031279661;12814.935925320407;12822.166559173624;12823.487060517567;11587.594434194847;12611.180273282207;12703.555375561904;12738.80891786624;13014.219146851125;10182;10255.435951022691;10363;10391.064804167661;10430.269881108325;3577.713782897388;3629.2560025195035;3637.4907512948207;3668.5414323209598;3670.082479380155;44609.10781784346;44739;44761;44774.55225447736;44790.77604611956;9072.86589176353;9163.58763855628;9210.769730756785;9355.4386736796;9384;17305.913470432566;17334.47996560105;17342.04618745981;17476.213570389362;17579.56051098733;6393.424591786761;6472.935270647327;6473.190851143607;6797.354251346114;7216.567005979553;3970.642642162219;3984.2230765000545;4062.5937406259136;4122.896927576835;4168.624823765876;3004.3690824926575;3028.6365636123805;3035.7267845893534;3047.0554128220324;3050.222193340728;500.9949900500948;502.36200025966815;509.06078285563075;511.2408074010092;519.4104691175543;485.6042325504702;597.5906504044743;606.1120458528277;611.7767015039567;663.9634820084846;7844.294013538809;7901;7959.641816118173;8264.628091735887;8348.373871959628;517.4515014085082;525.7134861500491;527.1618127177805;598.2730981857064;649.6978904809192;3483.5993860705835;3527.5061491391225;3629.927401451956;3692.593814680383;3901.726879118491;49893.75053124784;50568.747156263984;50664.49335506671;51384.486155138715;52045;43116;43507;44161.77919110384;44325.113497729864;44883.32675009877;44152.558474414844;44250.778746106065;44864.10271794611;44867;45307.54692453033;8195.221453961864;8338.24955753985;8584.871226931476;8744.387892847439;8816.294696424142;32554.744525547256;32691.836540817145;32970;33742.49386259257;34433;39490.40764388479;39749.2050159001;42283.577164227965;42419.57580424156;43148.352774709034;10421.166306695597;10487.790244195226;10626.36241825493;10719.785604288027;10976.615818446317;11845.5854045108;12232.26606403583;12242.387880605756;12290;12610.684732881751;10698.322632024414;10764.3541387517;10899.836502452312;11077.667669969915;11157;10966;11332.716682083013;11390.772184556428;11398.715032124263;11697.264781644015;11097.445127743742;11195.776084478428;11196.832047519456;11198.496067677137;11606.071514278668;9877.05958800781;10302.742431439274;10306.072453479223;10414.479276036016;10705.19711021661;8890.822183556422;9105.77235569116;9173.633054677739;9251.444913305224;9256.861147082935 23:06:04 INFO - PROCESS | 3413 | __end_tp_report 23:06:04 INFO - PROCESS | 3413 | __start_cc_report 23:06:04 INFO - PROCESS | 3413 | _x_x_mozilla_cycle_collect,1329 23:06:04 INFO - PROCESS | 3413 | __end_cc_report 23:06:04 INFO - PROCESS | 3413 | __startTimestamp1451545564590__endTimestamp 23:06:04 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | ------- Summary: start ------- 23:06:04 INFO - PROCESS | 3413 | Number of tests: 6 23:06:04 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | [#0] dojo.html Cycles:165 Average:5349.16 Median:5541.78 stddev:578.09 (10.4%) stddev-sans-first:554.69 23:06:04 INFO - PROCESS | 3413 | Values: 3198.2 3232.4 3261.5 3267.7 3292.8 5168.4 5305.1 5379.3 5400.9 5459.0 5329.4 5339.6 5348.0 5394.9 5495.8 4838.8 4903.3 4918.3 4976.9 4986.1 4912.6 4974.9 5009.8 5014.6 5072.2 4837.0 4973.6 4998.5 5070.2 5085.4 4814.9 4896.8 4923.5 4933.0 4936.1 4939.8 4943.5 4943.9 4960.7 4980.7 5222.9 5268.7 5276.2 5280.2 5293.5 5711.5 5767.2 5838.1 5840.1 5841.1 5696.0 5770.3 5789.0 5789.8 5792.8 5403.9 5404.9 5405.9 5413.9 5414.3 5765.2 5826.3 5874.5 5929.6 5949.0 5669.4 5670.8 5673.5 5674.1 5693.5 5721.4 5744.9 5761.9 5773.5 5828.4 5680.2 5700.5 5766.4 5794.8 5804.6 5773.7 5782.2 5787.2 5790.4 5811.1 4443.3 4443.8 4448.0 4454.0 4492.0 3942.7 3951.8 3975.2 4001.6 4027.7 5288.5 5316.1 5322.7 5323.8 5332.2 5717.7 5720.7 5722.8 5725.3 5770.2 5279.4 5305.7 5381.7 5382.4 5422.9 4863.9 4881.3 4886.0 4892.0 4927.4 5954.4 5959.5 5962.1 5963.1 5964.0 5541.8 5577.6 5706.5 5727.6 5729.0 5889.0 5903.6 5905.6 5906.4 5906.9 5644.7 5655.5 5656.3 5660.9 5662.7 5670.2 5779.7 5791.2 5798.6 5806.0 5581.3 5634.7 5669.8 5675.9 5680.7 5636.9 5650.3 5670.3 5670.3 5671.0 5508.9 5511.8 5514.0 5531.0 5552.7 5596.2 5613.2 5627.5 5647.5 5694.5 5477.1 5524.9 5527.3 5591.4 5600.2 23:06:04 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | [#1] ext.html Cycles:180 Average:44222.52 Median:342889.50 stddev:73809.64 (21.5%) stddev-sans-first:73943.78 23:06:04 INFO - PROCESS | 3413 | Values: 529.0 540.5 545.2 552.9 563.5 30058.5 31026.4 36798.1 37184.8 37228.0 38761.0 39311.8 39437.4 39469.6 39491.6 7296.0 8396.7 8565.1 8569.0 8570.5 7385.8 7481.3 7490.4 10014.0 10190.0 9939.4 10044.4 10051.6 10056.6 10062.2 9841.2 9905.2 9935.5 9939.5 9940.1 7415.5 7602.8 7605.0 9973.8 10199.8 39317.8 40080.6 40101.8 40122.2 40182.6 3191.2 3248.4 3561.5 3864.9 4048.3 30167.4 30206.4 30335.4 30835.5 31510.0 3823.8 3850.6 3899.5 3907.4 3988.7 274475.0 277088.0 277699.0 278842.6 278969.0 51781.0 59741.1 59797.4 60267.7 60313.4 52513.0 52531.5 65427.0 65960.7 66024.0 13929.0 14120.4 14263.5 14281.1 14291.2 10627.3 10702.1 10706.1 10712.5 10722.7 19313.0 20099.6 20299.7 20353.9 20360.5 7444.4 7597.4 7620.0 7621.2 7674.6 952.1 978.7 985.6 1075.9 1146.2 28418.6 28912.9 29464.9 29569.4 29581.6 999.0 1016.9 1023.3 1026.9 1124.0 13364.9 13431.1 13592.0 13725.9 13828.2 338039.0 342089.0 342635.0 343144.0 343539.0 51784.5 52093.2 52223.2 52243.0 52268.5 1303.2 1305.3 1320.7 1350.6 1352.4 24382.8 24492.5 25638.9 25683.5 25756.6 44311.6 44688.6 44785.8 44831.3 44869.0 208877.0 209765.0 209817.0 209845.0 210441.0 29077.4 29284.6 29662.3 29701.7 29706.6 39397.8 39845.4 43235.8 43819.3 44292.0 36838.8 36865.6 37708.0 38084.4 38538.2 30193.4 34726.5 36850.3 37637.6 37827.8 32810.0 33172.2 33565.8 33576.5 33608.5 22939.3 24273.2 24609.5 24613.1 25074.4 22816.9 22865.2 23155.3 24008.6 24193.6 23:06:04 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | [#2] jquery.html Cycles:180 Average:29359.40 Median:34770.57 stddev:92863.43 (267.1%) stddev-sans-first:93098.39 23:06:04 INFO - PROCESS | 3413 | Values: 347.2 347.8 350.6 355.0 356.5 16129.9 16762.8 16849.0 17285.5 17348.1 10603.6 11088.2 16726.6 16990.0 17012.3 4903.0 5003.0 5015.0 5016.8 5020.4 4974.3 5066.8 5276.7 5279.9 5283.7 3723.9 3841.3 5234.5 5239.1 5283.8 5373.6 5376.9 5414.2 5428.8 5433.1 5217.8 5223.8 6051.9 6249.0 6258.3 14273.1 14280.4 17513.5 18514.0 21319.6 2161.9 2173.7 2192.7 2212.7 2219.7 12176.5 14297.0 14323.6 14754.0 15210.4 1926.1 1929.1 1947.3 1956.2 1968.0 58711.4 59447.4 60145.4 61791.0 69509.7 9617.2 9798.3 10081.8 10083.5 14635.0 12025.8 12037.2 12130.8 12205.8 12287.0 6659.4 6800.3 6826.1 6852.5 6868.8 6608.7 6624.3 6675.9 6722.7 6723.5 4007.0 4007.1 4022.8 4428.6 4507.4 1821.8 1900.4 1901.5 1926.4 1959.0 824.6 917.3 931.1 1005.9 1060.5 13732.4 15228.6 15941.0 15964.1 16340.4 893.6 911.7 939.7 941.2 957.5 8346.5 8848.0 9336.9 9399.9 10133.7 543493.0 570969.0 572325.5 572385.0 582749.0 77405.6 79436.6 80994.6 81966.0 82731.0 1146.3 1158.3 1162.8 1166.6 1169.3 15386.7 15855.4 15867.4 15988.4 16183.9 33541.5 33596.2 34748.0 34793.1 35623.8 39981.4 40202.8 40448.4 40643.2 40881.8 9400.9 9466.2 9475.2 9540.4 9546.8 16534.2 16550.0 16672.8 16969.1 17202.1 14161.5 14681.1 14730.8 15077.9 15130.4 11014.9 13466.3 13673.5 13705.5 14019.5 13684.2 14042.9 14109.2 14346.6 14369.6 9466.6 9652.3 9753.8 9782.9 9786.3 11731.8 13839.4 13946.6 14827.9 15233.8 23:06:04 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | [#3] mootools.html Cycles:180 Average:5278.27 Median:6071.06 stddev:2758.57 (45.4%) stddev-sans-first:2749.77 23:06:04 INFO - PROCESS | 3413 | Values: 1260.6 1395.5 1405.5 1518.0 1753.5 6793.1 7151.7 7397.9 7447.6 7485.0 7337.2 7508.7 7525.0 7545.7 7549.1 2255.3 2276.7 2284.2 2292.8 2293.2 2257.5 2286.7 2312.9 2314.2 2323.0 2177.6 2178.7 2186.6 2205.6 2212.8 2188.0 2190.5 2219.1 2220.4 2222.3 2104.2 2132.0 2132.6 2132.8 2133.6 7419.4 7578.6 7586.4 7589.5 7604.1 4523.3 4610.9 4620.5 4629.6 4637.9 2913.3 3007.9 3008.0 3035.4 3094.6 961.7 985.6 987.4 1003.4 1011.9 10430.7 10479.2 10500.8 10502.7 10503.5 4060.7 4102.3 4143.1 4154.2 4224.0 8936.0 8951.6 8952.0 8976.1 8988.6 4612.4 4638.0 4641.4 4642.8 4647.0 3663.7 3733.6 4124.5 4230.0 4239.6 3567.5 3584.4 3600.1 3617.8 3627.5 1132.8 1144.1 1180.2 1183.3 1186.3 1015.4 1021.0 1213.9 1281.7 1301.9 7157.2 7175.9 7176.1 7255.4 7289.0 491.9 494.6 494.9 495.3 496.3 5731.8 5792.7 5793.2 5824.6 5974.5 9046.9 9058.5 9070.7 9082.9 9261.7 8582.3 8745.9 8893.3 8974.1 8999.5 7076.8 7164.4 7171.0 7176.7 7179.1 7058.4 7084.9 7113.6 7137.6 7210.0 8022.6 8122.6 8170.0 8327.1 8345.1 8292.3 8479.7 8485.6 8500.6 8645.5 6186.7 6307.8 6312.7 6773.7 6927.6 6830.0 6853.0 6997.6 7008.7 7025.8 6363.6 6614.2 6647.5 6709.0 6791.6 6731.0 6797.5 6801.2 6817.5 6862.0 6710.3 6774.2 6809.5 6814.1 6815.3 6029.2 6051.7 6053.6 6066.8 6075.4 6229.0 6248.4 6255.4 6260.3 6261.8 23:06:04 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | [#4] prototype.html Cycles:180 Average:5344.26 Median:6042.11 stddev:1603.13 (26.5%) stddev-sans-first:1562.94 23:06:04 INFO - PROCESS | 3413 | Values: 336.2 336.7 337.9 344.2 344.7 5843.6 5970.6 5985.3 5993.4 6027.1 5814.5 5934.6 5978.0 5981.3 5991.6 4631.7 4639.7 4646.0 4649.9 4652.7 4435.6 4522.2 4535.8 4636.5 4638.8 4559.8 4568.7 4577.3 4590.1 4606.7 4570.2 4588.3 4625.4 4629.6 4650.7 4065.6 4068.1 4069.6 4081.7 4090.1 5580.4 5645.4 5664.7 5699.2 5706.0 5392.2 5401.8 5407.2 5408.4 5415.1 6101.2 6326.2 6327.3 6331.7 6334.9 4364.7 4386.2 4475.1 4513.1 4513.6 6559.8 6584.2 6596.5 6600.9 6616.7 4613.0 4615.6 4616.7 4617.2 4623.1 4404.9 4547.7 4569.3 4676.3 4689.2 5259.4 5290.5 5313.1 5317.9 5318.3 6424.1 6432.7 6436.4 6438.0 6439.5 1602.9 1626.5 1628.1 1628.4 1628.6 1078.5 1081.1 1087.8 1100.9 1107.1 6058.2 6069.8 6072.9 6076.5 6087.7 6574.9 6589.0 6593.9 6599.3 6605.0 5929.4 6057.2 6071.8 6072.8 6077.7 3532.0 3567.1 3567.4 3568.8 3572.5 6837.9 6842.8 6843.1 6844.2 6854.0 6692.5 6697.4 6700.4 6711.6 6717.2 6666.6 6868.6 6871.2 6923.1 6927.3 6362.5 6481.1 6504.3 6598.9 6615.6 6443.8 6544.9 6553.5 6594.4 6596.2 6786.2 6801.4 6802.6 6807.0 6810.5 4451.8 4566.5 4605.7 4611.0 4623.4 6507.6 6526.2 6556.0 6607.3 6620.4 6415.5 6425.5 6431.0 6432.1 6439.5 6239.6 6407.1 6424.6 6427.6 6465.2 6357.1 6361.4 6362.6 6363.5 6369.0 6499.2 6504.2 6514.3 6522.5 6526.9 6180.2 6288.0 6306.3 6333.9 6334.1 23:06:04 INFO - PROCESS | 3413 | 23:06:04 INFO - PROCESS | 3413 | [#5] yui.html Cycles:180 Average:12948.91 Median:43829.78 stddev:14488.01 (33.1%) stddev-sans-first:14499.35 23:06:04 INFO - PROCESS | 3413 | Values: 680.6 702.7 746.0 769.2 769.3 13030.8 13271.3 13313.2 13404.1 13406.1 11686.2 11687.6 11699.1 11737.7 11812.7 671.0 678.6 807.9 819.1 825.5 827.0 830.3 836.0 836.2 836.4 853.8 861.4 868.3 870.7 872.4 700.4 708.4 856.2 866.7 867.0 629.3 635.7 637.3 637.7 638.4 12723.0 12796.9 12814.9 12822.2 12823.5 11587.6 12611.2 12703.6 12738.8 13014.2 10182.0 10255.4 10363.0 10391.1 10430.3 3577.7 3629.3 3637.5 3668.5 3670.1 44609.1 44739.0 44761.0 44774.6 44790.8 9072.9 9163.6 9210.8 9355.4 9384.0 17305.9 17334.5 17342.0 17476.2 17579.6 6393.4 6472.9 6473.2 6797.4 7216.6 3970.6 3984.2 4062.6 4122.9 4168.6 3004.4 3028.6 3035.7 3047.1 3050.2 501.0 502.4 509.1 511.2 519.4 485.6 597.6 606.1 611.8 664.0 7844.3 7901.0 7959.6 8264.6 8348.4 517.5 525.7 527.2 598.3 649.7 3483.6 3527.5 3629.9 3692.6 3901.7 49893.8 50568.7 50664.5 51384.5 52045.0 43116.0 43507.0 44161.8 44325.1 44883.3 44152.6 44250.8 44864.1 44867.0 45307.5 8195.2 8338.2 8584.9 8744.4 8816.3 32554.7 32691.8 32970.0 33742.5 34433.0 39490.4 39749.2 42283.6 42419.6 43148.4 10421.2 10487.8 10626.4 10719.8 10976.6 11845.6 12232.3 12242.4 12290.0 12610.7 10698.3 10764.4 10899.8 11077.7 11157.0 10966.0 11332.7 11390.8 11398.7 11697.3 11097.4 11195.8 11196.8 11198.5 11606.1 9877.1 10302.7 10306.1 10414.5 10705.2 8890.8 9105.8 9173.6 9251.4 9256.9 23:06:04 INFO - PROCESS | 3413 | -------- Summary: end -------- 23:06:04 INFO - PROCESS | 3413 | 23:06:05 INFO - TEST-INFO | 3413: exit 0 23:06:05 INFO - TEST-OK | dromaeo_css | took 1116062ms 23:06:05 INFO - TEST-START | kraken 23:06:05 INFO - operating with platform_type : linux_ 23:06:05 INFO - Initialising browser for kraken test... 23:06:05 INFO - TEST-INFO | started process 3602 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXiNi0Z/profile http://localhost:37276/getInfo.html) 23:06:09 INFO - PROCESS | 3602 | __metrics Screen width/height:1600/1200 23:06:09 INFO - PROCESS | 3602 | colorDepth:24 23:06:09 INFO - PROCESS | 3602 | Browser inner width/height: 1024/697 23:06:09 INFO - PROCESS | 3602 | __metrics 23:06:09 INFO - PROCESS | 3602 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 23:06:09 INFO - PROCESS | 3602 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:06:10 INFO - TEST-INFO | 3602: exit 0 23:06:10 INFO - Browser initialized. 23:06:10 INFO - Running cycle 1/1 for kraken test... 23:06:10 INFO - TEST-INFO | started process 3686 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXiNi0Z/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:06:22 INFO - PROCESS | 3686 | RSS: Main: 149987328 23:06:22 INFO - PROCESS | 3686 | 23:06:24 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37276/tests/kraken/driver.html?testName=audio-beat-detection) 23:06:25 INFO - PROCESS | 3686 | RSS: Main: 186281984 23:06:25 INFO - PROCESS | 3686 | 23:06:28 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37276/tests/kraken/driver.html?testName=audio-dft) 23:06:28 INFO - PROCESS | 3686 | RSS: Main: 175423488 23:06:28 INFO - PROCESS | 3686 | 23:06:31 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37276/tests/kraken/driver.html?testName=audio-fft) 23:06:32 INFO - PROCESS | 3686 | RSS: Main: 160645120 23:06:32 INFO - PROCESS | 3686 | 23:06:34 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37276/tests/kraken/driver.html?testName=audio-oscillator) 23:06:34 INFO - PROCESS | 3686 | RSS: Main: 163950592 23:06:34 INFO - PROCESS | 3686 | 23:06:37 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:06:37 INFO - PROCESS | 3686 | RSS: Main: 165154816 23:06:37 INFO - PROCESS | 3686 | 23:06:40 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37276/tests/kraken/driver.html?testName=imaging-darkroom) 23:06:41 INFO - PROCESS | 3686 | RSS: Main: 165228544 23:06:41 INFO - PROCESS | 3686 | 23:06:44 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37276/tests/kraken/driver.html?testName=imaging-desaturate) 23:06:44 INFO - PROCESS | 3686 | RSS: Main: 165855232 23:06:44 INFO - PROCESS | 3686 | 23:06:47 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37276/tests/kraken/driver.html?testName=json-parse-financial) 23:06:48 INFO - PROCESS | 3686 | RSS: Main: 166793216 23:06:48 INFO - PROCESS | 3686 | 23:06:49 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:06:50 INFO - PROCESS | 3686 | RSS: Main: 177422336 23:06:50 INFO - PROCESS | 3686 | 23:06:52 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37276/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:06:52 INFO - PROCESS | 3686 | RSS: Main: 176132096 23:06:52 INFO - PROCESS | 3686 | 23:06:55 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:06:55 INFO - PROCESS | 3686 | RSS: Main: 187629568 23:06:55 INFO - PROCESS | 3686 | 23:06:57 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:06:58 INFO - PROCESS | 3686 | RSS: Main: 193216512 23:06:58 INFO - PROCESS | 3686 | 23:07:01 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:07:01 INFO - PROCESS | 3686 | RSS: Main: 192806912 23:07:01 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | Cycle 1(1): loaded http://localhost:37276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37276/tests/kraken/driver.html?testName=ai-astar) 23:07:03 INFO - PROCESS | 3686 | RSS: Main: 248745984 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | __start_tp_report 23:07:03 INFO - PROCESS | 3686 | _x_x_mozilla_page_load 23:07:03 INFO - PROCESS | 3686 | _x_x_mozilla_page_load_details 23:07:03 INFO - PROCESS | 3686 | |i|pagename|runs| 23:07:03 INFO - PROCESS | 3686 | |0;ai-astar;110;111;103;111;110;111;100;109;110;101 23:07:03 INFO - PROCESS | 3686 | |1;audio-beat-detection;145;124;154;163;129;153;150;134;177;129 23:07:03 INFO - PROCESS | 3686 | |2;audio-dft;160;163;159;162;159;159;161;163;162;161 23:07:03 INFO - PROCESS | 3686 | |3;audio-fft;71;72;80;94;72;71;71;113;90;71 23:07:03 INFO - PROCESS | 3686 | |4;audio-oscillator;96;96;139;95;97;97;142;96;96;140 23:07:03 INFO - PROCESS | 3686 | |5;imaging-gaussian-blur;109;109;109;109;110;109;110;109;109;110 23:07:03 INFO - PROCESS | 3686 | |6;imaging-darkroom;146;146;145;145;145;146;145;145;145;145 23:07:03 INFO - PROCESS | 3686 | |7;imaging-desaturate;95;95;96;95;96;95;95;96;95;95 23:07:03 INFO - PROCESS | 3686 | |8;json-parse-financial;58;58;58;57;56;57;57;67;55;55 23:07:03 INFO - PROCESS | 3686 | |9;json-stringify-tinderbox;68;69;68;69;70;69;69;69;70;68 23:07:03 INFO - PROCESS | 3686 | |10;stanford-crypto-aes;94;93;86;87;87;86;88;94;95;86 23:07:03 INFO - PROCESS | 3686 | |11;stanford-crypto-ccm;116;133;117;117;117;124;124;114;118;126 23:07:03 INFO - PROCESS | 3686 | |12;stanford-crypto-pbkdf2;142;142;158;150;152;143;152;153;154;142 23:07:03 INFO - PROCESS | 3686 | |13;stanford-crypto-sha256-iterative;70;68;70;71;67;67;67;69;67;68 23:07:03 INFO - PROCESS | 3686 | __end_tp_report 23:07:03 INFO - PROCESS | 3686 | __start_cc_report 23:07:03 INFO - PROCESS | 3686 | _x_x_mozilla_cycle_collect,938 23:07:03 INFO - PROCESS | 3686 | __end_cc_report 23:07:03 INFO - PROCESS | 3686 | __startTimestamp1451545623604__endTimestamp 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | ------- Summary: start ------- 23:07:03 INFO - PROCESS | 3686 | Number of tests: 14 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#0] ai-astar Cycles:10 Average:107.60 Median:110.00 stddev:4.43 (4.0%) stddev-sans-first:4.61 23:07:03 INFO - PROCESS | 3686 | Values: 110.0 111.0 103.0 111.0 110.0 111.0 100.0 109.0 110.0 101.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:151.50 stddev:16.95 (11.2%) stddev-sans-first:17.98 23:07:03 INFO - PROCESS | 3686 | Values: 145.0 124.0 154.0 163.0 129.0 153.0 150.0 134.0 177.0 129.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.50 stddev:1.60 (1.0%) stddev-sans-first:1.66 23:07:03 INFO - PROCESS | 3686 | Values: 160.0 163.0 159.0 162.0 159.0 159.0 161.0 163.0 162.0 161.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#3] audio-fft Cycles:10 Average:80.50 Median:72.00 stddev:14.28 (19.8%) stddev-sans-first:14.72 23:07:03 INFO - PROCESS | 3686 | Values: 71.0 72.0 80.0 94.0 72.0 71.0 71.0 113.0 90.0 71.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:96.00 stddev:21.37 (22.3%) stddev-sans-first:22.10 23:07:03 INFO - PROCESS | 3686 | Values: 96.0 96.0 139.0 95.0 97.0 97.0 142.0 96.0 96.0 140.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 23:07:03 INFO - PROCESS | 3686 | Values: 109.0 109.0 109.0 109.0 110.0 109.0 110.0 109.0 109.0 110.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 23:07:03 INFO - PROCESS | 3686 | Values: 146.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 23:07:03 INFO - PROCESS | 3686 | Values: 95.0 95.0 96.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:57.50 stddev:3.43 (6.0%) stddev-sans-first:3.63 23:07:03 INFO - PROCESS | 3686 | Values: 58.0 58.0 58.0 57.0 56.0 57.0 57.0 67.0 55.0 55.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 23:07:03 INFO - PROCESS | 3686 | Values: 68.0 69.0 68.0 69.0 70.0 69.0 69.0 69.0 70.0 68.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:90.50 stddev:3.86 (4.3%) stddev-sans-first:3.76 23:07:03 INFO - PROCESS | 3686 | Values: 94.0 93.0 86.0 87.0 87.0 86.0 88.0 94.0 95.0 86.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#11] stanford-crypto-ccm Cycles:10 Average:120.60 Median:121.00 stddev:5.93 (4.9%) stddev-sans-first:6.05 23:07:03 INFO - PROCESS | 3686 | Values: 116.0 133.0 117.0 117.0 117.0 124.0 124.0 114.0 118.0 126.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.80 Median:152.00 stddev:6.00 (3.9%) stddev-sans-first:5.83 23:07:03 INFO - PROCESS | 3686 | Values: 142.0 142.0 158.0 150.0 152.0 143.0 152.0 153.0 154.0 142.0 23:07:03 INFO - PROCESS | 3686 | 23:07:03 INFO - PROCESS | 3686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:1.51 (2.2%) stddev-sans-first:1.48 23:07:03 INFO - PROCESS | 3686 | Values: 70.0 68.0 70.0 71.0 67.0 67.0 67.0 69.0 67.0 68.0 23:07:03 INFO - PROCESS | 3686 | -------- Summary: end -------- 23:07:03 INFO - PROCESS | 3686 | 23:07:04 INFO - TEST-INFO | 3686: exit 0 23:07:04 INFO - TEST-OK | kraken | took 59054ms 23:07:04 INFO - TEST-START | v8_7 23:07:04 INFO - operating with platform_type : linux_ 23:07:04 INFO - Initialising browser for v8_7 test... 23:07:04 INFO - TEST-INFO | started process 3932 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXHLukr/profile http://localhost:37276/getInfo.html) 23:07:08 INFO - PROCESS | 3932 | __metrics Screen width/height:1600/1200 23:07:08 INFO - PROCESS | 3932 | colorDepth:24 23:07:08 INFO - PROCESS | 3932 | Browser inner width/height: 1024/697 23:07:08 INFO - PROCESS | 3932 | __metrics 23:07:08 INFO - PROCESS | 3932 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 23:07:08 INFO - PROCESS | 3932 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:07:09 INFO - TEST-INFO | 3932: exit 0 23:07:09 INFO - Browser initialized. 23:07:09 INFO - Running cycle 1/1 for v8_7 test... 23:07:09 INFO - TEST-INFO | started process 4012 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXHLukr/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:21 INFO - PROCESS | 4012 | RSS: Main: 149082112 23:07:21 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | Cycle 1(1): loaded http://localhost:37276/tests/v8_7/run.html (next: http://localhost:37276/tests/v8_7/run.html) 23:07:42 INFO - PROCESS | 4012 | RSS: Main: 575696896 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | __start_tp_report 23:07:42 INFO - PROCESS | 4012 | _x_x_mozilla_page_load 23:07:42 INFO - PROCESS | 4012 | _x_x_mozilla_page_load_details 23:07:42 INFO - PROCESS | 4012 | |i|pagename|runs| 23:07:42 INFO - PROCESS | 4012 | |0;Richards;167.78523489932886 23:07:42 INFO - PROCESS | 4012 | |1;DeltaBlue;190.47619047619048 23:07:42 INFO - PROCESS | 4012 | |2;Encrypt;280.50490883590464 23:07:42 INFO - PROCESS | 4012 | |3;Decrypt;5263.1578947368425 23:07:42 INFO - PROCESS | 4012 | |4;RayTrace;1951.2670565302144 23:07:42 INFO - PROCESS | 4012 | |5;Earley;797.4481658692185 23:07:42 INFO - PROCESS | 4012 | |6;Boyer;12500 23:07:42 INFO - PROCESS | 4012 | |7;RegExp;26025.641025641027 23:07:42 INFO - PROCESS | 4012 | |8;Splay;636.9426751592357 23:07:42 INFO - PROCESS | 4012 | |9;NavierStokes;4840.579710144928 23:07:42 INFO - PROCESS | 4012 | __end_tp_report 23:07:42 INFO - PROCESS | 4012 | __startTimestamp1451545662978__endTimestamp 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | ------- Summary: start ------- 23:07:42 INFO - PROCESS | 4012 | Number of tests: 10 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#0] Richards Cycles:1 Average:167.79 Median:167.79 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 167.8 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#1] DeltaBlue Cycles:1 Average:190.48 Median:190.48 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 190.5 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#2] Encrypt Cycles:1 Average:280.50 Median:280.50 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 280.5 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#3] Decrypt Cycles:1 Average:5263.16 Median:5263.16 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 5263.2 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#4] RayTrace Cycles:1 Average:1951.27 Median:1951.27 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 1951.3 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#5] Earley Cycles:1 Average:797.45 Median:797.45 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 797.4 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#6] Boyer Cycles:1 Average:12500.00 Median:12500.00 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 12500.0 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#7] RegExp Cycles:1 Average:26025.64 Median:26025.64 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 26025.6 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#8] Splay Cycles:1 Average:636.94 Median:636.94 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 636.9 23:07:42 INFO - PROCESS | 4012 | 23:07:42 INFO - PROCESS | 4012 | [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 23:07:42 INFO - PROCESS | 4012 | Values: 4840.6 23:07:42 INFO - PROCESS | 4012 | -------- Summary: end -------- 23:07:42 INFO - PROCESS | 4012 | 23:07:43 INFO - TEST-INFO | 4012: exit 0 23:07:43 INFO - TEST-OK | v8_7 | took 39400ms 23:07:43 INFO - SUITE-END | took 1214s 23:07:44 INFO - Completed test suite (00:20:14) 23:07:44 INFO - Working with test: dromaeo_css 23:07:44 INFO - Generating results file: dromaeo_css 23:07:44 INFO - Working with test: kraken 23:07:44 INFO - Generating results file: kraken 23:07:44 INFO - javascript benchmark 23:07:44 INFO - Working with test: v8_7 23:07:44 INFO - Generating results file: v8_7 23:07:44 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:07:44 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 23:07:44 INFO - process_Request line: dromaeo_css 6850.00 graph.html#tests=[[72,201,35]] 23:07:44 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:07:44 INFO - process_Request line: kraken 1508.20 graph.html#tests=[[232,201,35]] 23:07:44 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:07:44 INFO - process_Request line: v8_7 19620.72 graph.html#tests=[[230,201,35]] 23:07:44 INFO - RETURN: dromaeo_css: 6850.00 23:07:44 INFO - RETURN: kraken: 1508.20 23:07:44 INFO - RETURN: v8_7: 19620.72 23:07:44 INFO - javascript benchmark 23:07:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3198.2164369729417, 3232.4181647303476, 3261.527078573607, 3267.722243609293, 3292.7859689120214, 5168.431472538018, 5305.098133317336, 5379.300690910181, 5400.918986215205, 5458.999999999995, 5329.360476742787, 5339.6262261641705, 5348.037353276409, 5394.946050539494, 5495.752691128899, 4838.782254798533, 4903.289023091667, 4918.336024636664, 4976.875578110558, 4986.127427700156, 4912.631552633549, 4974.925376119361, 5009.799608015675, 5014.5737612303, 5072.213806859924, 4836.951630483721, 4973.62697797663, 4998.450170481244, 5070.2141168119, 5085.415177254607, 4814.903701925977, 4896.804127834882, 4923.507649235084, 4932.975335123301, 4936.111499930011, 4939.777710003059, 4943.480934501861, 4943.901121977577, 4960.677555958851, 4980.7011579305, 5222.895542089175, 5268.6575372600655, 5276.2085687146855, 5280.18157185633, 5293.497117773805, 5711.514521265656, 5767.192593036978, 5838.065909454509, 5840.123981402781, 5841.123831425278, 5695.971520142414, 5770.278715160605, 5789.015867302526, 5789.8263052108505, 5792.82621521355, 5403.945960540423, 5404.864878377994, 5405.945940540583, 5413.945860541384, 5414.296141501619, 5765.221695071195, 5826.329972053268, 5874.500667443315, 5929.6145750526075, 5949.0, 5669.43305669438, 5670.8015219467125, 5673.546116310686, 5674.148877668383, 5693.544516438675, 5721.427857214246, 5744.942550574441, 5761.88476230473, 5773.538116950634, 5828.358880523139, 5680.176374425685, 5700.543956483554, 5766.365699773021, 5794.768209271666, 5804.564657650693, 5773.740181691834, 5782.219400380979, 5787.218725472091, 5790.392008839095, 5811.099279611667, 4443.289073748185, 4443.755593442327, 4448.021435284232, 4454.042380888124, 4492.011757413364, 3942.743721658083, 3951.7628942263555, 3975.2372101389833, 4001.599840015975, 4027.7180597357906, 5288.497592728685, 5316.122839731478, 5322.690606167648, 5323.840284791462, 5332.226827110047, 5717.714114294268, 5720.742566584514, 5722.799702010409, 5725.2843394575675, 5770.221020162308, 5279.44565820591, 5305.734713264322, 5381.677099373972, 5382.3541175059245, 5422.864428389243, 4863.878403039882, 4881.341018962395, 4886.047220791911, 4892.046051020088, 4927.433345165352, 5954.37479064692, 5959.523238140939, 5962.07587823888, 5963.135345374896, 5964.015937370372, 5541.778328866962, 5577.637453565505, 5706.457886500942, 5727.570432217517, 5729.026065568946, 5888.970555147368, 5903.557233207613, 5905.616134951214, 5906.40935906406, 5906.911396329145, 5644.689542075225, 5655.547556195412, 5656.264685590847, 5660.943390566042, 5662.745176466987, 5670.234518339971, 5779.7110144493445, 5791.218185544897, 5798.59409841307, 5805.970970145122, 5581.330240371181, 5634.718264086861, 5669.801556945487, 5675.8864822704145, 5680.68756218412, 5636.915446268391, 5650.29371328584, 5670.262865827416, 5670.347909990239, 5671.035923892866, 5508.944910550843, 5511.779528818963, 5513.972430137824, 5531.004419204582, 5552.694601796778, 5596.216529685928, 5613.158026295925, 5627.465390787868, 5647.5199608032135, 5694.4590263924865, 5477.123660214506, 5524.861878453071, 5527.309086364205, 5591.384947655673, 5600.243967064557], "unit": "score", "name": "dojo.html", "value": 5312.593825804953}, {"lowerIsBetter": false, "replicates": [528.9869899143141, 540.4946375139245, 545.2176127257384, 552.9060059789837, 563.4590792838875, 30058.549121763164, 31026.37947241057, 36798.08004799881, 37184.814075929586, 37228.0, 38761.0, 39311.803440982825, 39437.40843887344, 39469.605303946955, 39491.60508394915, 7295.963520182391, 8396.748097557083, 8565.100664430238, 8568.957155214215, 8570.485770853738, 7385.815354616124, 7481.289277518627, 7490.3633191178815, 10014.048665376815, 10190.0, 9939.40363578184, 10044.447555384451, 10051.597936082548, 10056.648017319396, 10062.19502439803, 9841.212702983745, 9905.20758339331, 9935.453550054744, 9939.5030248488, 9940.105390514816, 7415.480916335859, 7602.809929751746, 7605.049368828896, 9973.80052398948, 10199.847002294971, 39317.803410982764, 40080.59919400798, 40101.79949100265, 40122.19755604872, 40182.59817401818, 3191.1702957231287, 3248.3503299340227, 3561.5370001899846, 3864.942025869614, 4048.332025215836, 30167.396652066836, 30206.395872082434, 30335.393292134035, 30835.537466938014, 31510.0, 3823.836775317243, 3850.5764365919727, 3899.473571067926, 3907.355286377744, 3988.720789544733, 274475.0, 277088.0, 277699.0, 278842.6057869718, 278969.0, 51781.0, 59741.103883441785, 59797.40202598005, 60267.69866150641, 60313.39686603165, 52513.0, 52531.47468525266, 65427.0, 65960.67019664968, 66024.0, 13929.0, 14120.364583593762, 14263.500777472738, 14281.071730337495, 14291.213983230815, 10627.256092073481, 10702.09032232269, 10706.1435085193, 10712.46437678113, 10722.678319650424, 19313.009789958855, 20099.598008039757, 20299.69550456715, 20353.898230508752, 20360.490987725425, 7444.367228785614, 7597.392208623297, 7620.0, 7621.199774023758, 7674.577898215652, 952.1430712358932, 978.6672531339378, 985.5614251657944, 1075.876274228458, 1146.2434793036555, 28418.573721393783, 28912.855435722686, 29464.852675736485, 29569.408611827643, 29581.55627665587, 998.960041598328, 1016.9186465082776, 1023.3297190340339, 1026.9383836969805, 1123.9831402528969, 13364.933175334061, 13431.069522187989, 13592.0, 13725.862741372659, 13828.239446830521, 338039.0, 342089.0, 342635.0, 343144.0, 343539.0, 51784.48215517797, 52093.218601721004, 52223.216651750256, 52243.0, 52268.47731522712, 1303.1724854717322, 1305.321232958875, 1320.7490576790378, 1350.581319790868, 1352.4319785689836, 24382.75617243805, 24492.510149796548, 25638.87180564085, 25683.486330272917, 25756.61365079563, 44311.55688443074, 44688.553114468436, 44785.776071119435, 44831.32753008642, 44869.0, 208877.0, 209765.0, 209817.0, 209845.0, 210441.0, 29077.41845163127, 29284.56073158947, 29662.258443539085, 29701.702982969895, 29706.55440168356, 39397.80301098476, 39845.41777695602, 43235.783821080695, 43819.342709860015, 44292.0, 36838.8158059208, 36865.63134368622, 37708.0, 38084.428733568464, 38538.229235414576, 30193.396132077676, 34726.47910281297, 36850.26299474049, 37637.623623764506, 37827.810860945516, 32810.0, 33172.1706957328, 33565.83217083899, 33576.49635255522, 33608.495872562424, 22939.311820645406, 24273.150439734523, 24609.507809843344, 24613.13854015101, 25074.430364596814, 22816.885915570318, 22865.19971800979, 23155.3053408398, 24008.63987040231, 24193.637095443934], "unit": "score", "name": "ext.html", "value": 17904.244342388298}, {"lowerIsBetter": false, "replicates": [347.2222222222222, 347.8191340502938, 350.5950627025786, 355.04847010013157, 356.5080189338712, 16129.919350403261, 16762.83237167628, 16849.0, 17285.48143555692, 17348.132593370345, 10603.575856965712, 11088.2238243323, 16726.58183545412, 16990.0, 17012.319507219698, 4903.043906438254, 5002.974985125069, 5015.0, 5016.774245158977, 5020.422651395082, 4974.253861920704, 5066.847994560169, 5276.709780962045, 5279.894402111955, 5283.735813209324, 3723.864221412468, 3841.308564458396, 5234.450382709818, 5239.083160446907, 5283.788648454057, 5373.623846330739, 5376.865578360573, 5414.214938833847, 5428.83713488596, 5433.1035379162395, 5217.8173763918085, 5223.843284701465, 6051.878962420727, 6248.96875515624, 6258.280297765769, 14273.072250303698, 14280.428782848674, 17513.47459576215, 18514.0, 21319.614225075322, 2161.8810965396897, 2173.673948907661, 2192.6710993350966, 2212.6570381590877, 2219.678146668724, 12176.452059657247, 14297.0, 14323.641908952255, 14753.967222294445, 15210.391584336614, 1926.1139875657213, 1929.1222493765304, 1947.2892394275987, 1956.2370675436591, 1967.9704804427945, 58711.41288587145, 59447.40552594462, 60145.39854601442, 61791.0, 69509.65245173793, 9617.182539484222, 9798.31411801167, 10081.848772268422, 10083.49582520871, 14634.975551711492, 12025.759484810254, 12037.157398982163, 12130.757384852253, 12205.755884882252, 12287.0, 6659.367360100783, 6800.31996800316, 6826.146731658543, 6852.451803855779, 6868.759593414206, 6608.702608382634, 6624.304448032887, 6675.899861502081, 6722.66386680664, 6723.462123030145, 4007.0383108053857, 4007.0583412897945, 4022.8189731462153, 4428.627125591012, 4507.414036175276, 1821.8451431628196, 1900.439370385712, 1901.4580844459263, 1926.373928473252, 1958.9706154407695, 824.583585289425, 917.3349321741817, 931.0968360690119, 1005.939643621385, 1060.522764755863, 13732.366223074796, 15228.619284517754, 15941.0, 15964.121973291349, 16340.428085016967, 893.5934149961752, 911.7401540560908, 939.736873675372, 941.1764705882352, 957.5308099031424, 8346.499210047417, 8847.955760221157, 9336.906630933603, 9399.90600094004, 10133.746656333504, 543493.0, 570969.0, 572325.4907058302, 572385.0, 582749.0, 77405.61297193478, 79436.6028169867, 80994.5950270245, 81966.0, 82731.0, 1146.3465824480134, 1158.3339579741514, 1162.84301619282, 1166.585862018988, 1169.2984209474419, 15386.69226615439, 15855.445059422866, 15867.365305387655, 15988.440404585785, 16183.867129300836, 33541.496877547346, 33596.160095997795, 34748.0, 34793.13017174591, 35623.82188089043, 39981.40027899526, 40202.798986004884, 40448.3932741015, 40643.187136256514, 40881.79559102185, 9400.91889432724, 9466.242700584075, 9475.24198064167, 9540.427574345562, 9546.761330966503, 16534.189187838336, 16550.0, 16672.83327166713, 16969.066701331052, 17202.13989300555, 14161.50056425266, 14681.119132852064, 14730.77903831422, 15077.924610376878, 15130.394784208509, 11014.859435766395, 13466.326683665973, 13673.453061877812, 13705.451781928612, 14019.50931717385, 13684.247366394944, 14042.929785351416, 14109.153450792985, 14346.641333966734, 14369.64075898111, 9466.574004169966, 9652.324337296322, 9753.756156096155, 9782.902170978485, 9786.266030047633, 11731.765364692828, 13839.446422143, 13946.58160255194, 14827.92586037106, 15233.847661523687], "unit": "score", "name": "jquery.html", "value": 8763.657470607663}, {"lowerIsBetter": false, "replicates": [1260.6281147061616, 1395.5324966136347, 1405.4588983241451, 1517.962050948726, 1753.5353131420177, 6793.116894803675, 7151.678174482147, 7397.889031664523, 7447.553146811181, 7485.0, 7337.19290878003, 7508.737194198204, 7524.96237518813, 7545.698172073125, 7549.131849837257, 2255.2783109404995, 2276.658501224821, 2284.246198754407, 2292.805111565519, 2293.2317673579287, 2257.5146343536135, 2286.6570014497793, 2312.861228326306, 2314.155333303349, 2323.0, 2177.5862586108587, 2178.705874706918, 2186.606410846047, 2205.647096464566, 2212.83403744719, 2188.0481990334256, 2190.463336482564, 2219.079082180901, 2220.378294077661, 2222.2666520048424, 2104.2214380679093, 2131.997960958347, 2132.648113061343, 2132.776058513865, 2133.626615342309, 7419.443541734391, 7578.583177925212, 7586.431017673642, 7589.468737188399, 7604.125525564576, 4523.253663145577, 4610.9077818443775, 4620.491745907947, 4629.606483448911, 4637.930431043537, 2913.257119434552, 3007.8947236846825, 3007.9699203008126, 3035.362573859492, 3094.5512900629506, 961.6634178037701, 985.5663508056432, 987.4026214140486, 1003.3829195045067, 1011.8836333821631, 10430.739231519197, 10479.21405894561, 10500.78998420035, 10502.68491945243, 10503.52734126966, 4060.715749897538, 4102.343625019982, 4143.08852052548, 4154.169166166779, 4224.028473451123, 8936.0, 8951.597178127, 8952.015278319379, 8976.14726600985, 8988.59551320192, 4612.400387949545, 4638.0, 4641.419822522184, 4642.837500687459, 4647.0, 3663.725220608465, 3733.6266373362446, 4124.463819703419, 4229.984803647102, 4239.639630631431, 3567.4648802679285, 3584.372734771405, 3600.0999750062483, 3617.764845285101, 3627.510286111394, 1132.7904337697553, 1144.1190283481671, 1180.221054104287, 1183.2782002978176, 1186.2526608236756, 1015.3704703083987, 1021.0249212002519, 1213.8725433829497, 1281.6731733407921, 1301.8958483321312, 7157.2127066022695, 7175.928240717631, 7176.138863336433, 7255.383292420098, 7289.015982842355, 491.9458859525521, 494.56972433981946, 494.92328689053255, 495.28184133007284, 496.31260703925324, 5731.824975879935, 5792.681402522901, 5793.24687790593, 5824.621399609096, 5974.522038237017, 9046.909530904739, 9058.54707264634, 9070.727878163665, 9082.86375704365, 9261.67584134552, 8582.270507006975, 8745.868811967826, 8893.333000024895, 8974.102589740975, 8999.505027223566, 7076.823079423056, 7164.391026762679, 7171.03191069199, 7176.712931482786, 7179.066721326299, 7058.400035996998, 7084.858302834017, 7113.57318560888, 7137.571745695067, 7209.963950180216, 8022.638981245741, 8122.634481448231, 8170.01959764832, 8327.08477050294, 8345.123762004901, 8292.253697167283, 8479.745607631781, 8485.57572121379, 8500.574971251537, 8645.481271123752, 6186.721597528032, 6307.842303942254, 6312.652804095819, 6773.661316934035, 6927.58434493932, 6830.043793868962, 6853.0, 6997.580145191305, 7008.684609192496, 7025.754098606524, 6363.649999250086, 6614.206295244601, 6647.534672572873, 6708.966455167693, 6791.558548694319, 6731.0, 6797.4901882358035, 6801.18385793708, 6817.454603631599, 6861.965690171517, 6710.328967103251, 6774.153230846145, 6809.5233333666165, 6814.1482314710665, 6815.318468153145, 6029.216201893902, 6051.697415129314, 6053.60651557665, 6066.757329706762, 6075.362086980804, 6228.968855155696, 6248.4064013918605, 6255.437010669061, 6260.28006779208, 6261.780837670842], "unit": "score", "name": "mootools.html", "value": 4268.181107845687}, {"lowerIsBetter": false, "replicates": [336.2065525360148, 336.69529076186046, 337.8749862550857, 344.22892720306515, 344.7431663410758, 5843.590948633598, 5970.6417614943075, 5985.34161242263, 5993.400659934005, 6027.065804800252, 5814.534837213024, 5934.554908381878, 5978.0, 5981.252343457068, 5991.640501569909, 4631.722096674193, 4639.675222734419, 4645.9767701161445, 4649.93025104622, 4652.74409907455, 4435.600795928366, 4522.2312206924735, 4535.750533720644, 4636.51316611756, 4638.814447422099, 4559.772011399434, 4568.703034302776, 4577.267637178053, 4590.059037897223, 4606.700564463316, 4570.245909424941, 4588.26587745959, 4625.444946606395, 4629.560191781776, 4650.697704649221, 4065.6340929316207, 4068.0643452005907, 4069.613386728256, 4081.653059489947, 4090.079732060263, 5580.405956143991, 5645.350784659776, 5664.6601203927485, 5699.173619825144, 5706.0, 5392.164214546712, 5401.756920938567, 5407.242985981966, 5408.378036525811, 5415.10650742627, 6101.237345331833, 6326.240851097852, 6327.335629758855, 6331.651759153245, 6334.936650633481, 4364.694471387004, 4386.188555117282, 4475.10497900418, 4513.119941611387, 4513.638908887281, 6559.7704080357435, 6584.176977877765, 6596.472282217459, 6600.93399066008, 6616.702248398834, 4612.953870461252, 4615.630749540029, 4616.676832621684, 4617.168909596237, 4623.052274283764, 4404.911901761947, 4547.704399214041, 4569.2689169732685, 4676.275177347517, 4689.249720044844, 5259.447757985433, 5290.497402746733, 5313.0967735485065, 5317.867053323621, 5318.25544423781, 6424.100625912376, 6432.678366081677, 6436.388543088399, 6438.034294855809, 6439.484841212692, 1602.8557429831371, 1626.4632671218706, 1628.0882705684855, 1628.3974929276244, 1628.6498402843445, 1078.5200585739428, 1081.1026847716375, 1087.8096333141668, 1100.862392200975, 1107.0755918807886, 6058.242719660043, 6069.817905462844, 6072.878542429126, 6076.513878889678, 6087.726052327655, 6574.934250657528, 6588.967055164693, 6593.901091483632, 6599.340065993459, 6605.0, 5929.436703513159, 6057.151998720183, 6071.848203794941, 6072.817815465543, 6077.696115194222, 3531.964680353215, 3567.0725611341236, 3567.3935430976276, 3568.750187486903, 3572.5355703758346, 6837.931620683829, 6842.794716158523, 6843.076184715, 6844.2129155147795, 6854.0, 6692.531522793455, 6697.397234248941, 6700.430463410567, 6711.597304161766, 6717.227518835299, 6666.600003999775, 6868.587884726932, 6871.244163142051, 6923.099997000358, 6927.341902519353, 6362.491000720024, 6481.092647029325, 6504.317046710027, 6598.934010659832, 6615.636140012345, 6443.838904027437, 6544.901826472702, 6553.5084868635995, 6594.439472644687, 6596.208454985433, 6786.185657721105, 6801.353871382211, 6802.625855577793, 6806.965965170142, 6810.523263371517, 4451.844185453623, 4566.4748553916725, 4605.677602567788, 4610.953890461053, 4623.398958135421, 6507.609543427409, 6526.216853977553, 6555.967220163869, 6607.3062328456745, 6620.404163625297, 6415.518836087219, 6425.518086143651, 6431.035344698333, 6432.09950606906, 6439.484841212786, 6239.625622462667, 6407.135036769976, 6424.582402143846, 6427.55007149495, 6465.191851018622, 6357.078223657451, 6361.363863613787, 6362.618242905441, 6363.459105976044, 6369.044643303541, 6499.1876015498065, 6504.1869766279215, 6514.283428822731, 6522.543421960418, 6526.934730652632, 6180.165677633642, 6287.96248618991, 6306.306306306211, 6333.936660633335, 6334.081558174131], "unit": "score", "name": "prototype.html", "value": 4823.632358731386}, {"lowerIsBetter": false, "replicates": [680.5542369747814, 702.7399862051042, 746.0078096132145, 769.1885061260368, 769.3076231391747, 13030.804537931926, 13271.270080145589, 13313.201207927532, 13404.128731632438, 13406.06157568968, 11686.240394374381, 11687.649370518877, 11699.064074874012, 11737.706557336049, 11812.704682382944, 671.0069097735354, 678.5792808458763, 807.858624740671, 819.1194465949128, 825.5418242875202, 826.9503829770233, 830.3066939105854, 836.0427310729242, 836.2097817562405, 836.3685417509802, 853.8121613245077, 861.3539845116163, 868.3183700794877, 870.6778491957984, 872.3936863879607, 700.3871612339203, 708.4367927497626, 856.2465030772911, 866.7053201911381, 867.0245973280059, 629.3014753623474, 635.6694518850172, 637.3053371824733, 637.6588525139028, 638.3743930947651, 12723.0, 12796.872031279661, 12814.935925320407, 12822.166559173624, 12823.487060517567, 11587.594434194847, 12611.180273282207, 12703.555375561904, 12738.80891786624, 13014.219146851125, 10182.0, 10255.435951022691, 10363.0, 10391.064804167661, 10430.269881108325, 3577.713782897388, 3629.2560025195035, 3637.4907512948207, 3668.5414323209598, 3670.082479380155, 44609.10781784346, 44739.0, 44761.0, 44774.55225447736, 44790.77604611956, 9072.86589176353, 9163.58763855628, 9210.769730756785, 9355.4386736796, 9384.0, 17305.913470432566, 17334.47996560105, 17342.04618745981, 17476.213570389362, 17579.56051098733, 6393.424591786761, 6472.935270647327, 6473.190851143607, 6797.354251346114, 7216.567005979553, 3970.642642162219, 3984.2230765000545, 4062.5937406259136, 4122.896927576835, 4168.624823765876, 3004.3690824926575, 3028.6365636123805, 3035.7267845893534, 3047.0554128220324, 3050.222193340728, 500.9949900500948, 502.36200025966815, 509.06078285563075, 511.2408074010092, 519.4104691175543, 485.6042325504702, 597.5906504044743, 606.1120458528277, 611.7767015039567, 663.9634820084846, 7844.294013538809, 7901.0, 7959.641816118173, 8264.628091735887, 8348.373871959628, 517.4515014085082, 525.7134861500491, 527.1618127177805, 598.2730981857064, 649.6978904809192, 3483.5993860705835, 3527.5061491391225, 3629.927401451956, 3692.593814680383, 3901.726879118491, 49893.75053124784, 50568.747156263984, 50664.49335506671, 51384.486155138715, 52045.0, 43116.0, 43507.0, 44161.77919110384, 44325.113497729864, 44883.32675009877, 44152.558474414844, 44250.778746106065, 44864.10271794611, 44867.0, 45307.54692453033, 8195.221453961864, 8338.24955753985, 8584.871226931476, 8744.387892847439, 8816.294696424142, 32554.744525547256, 32691.836540817145, 32970.0, 33742.49386259257, 34433.0, 39490.40764388479, 39749.2050159001, 42283.577164227965, 42419.57580424156, 43148.352774709034, 10421.166306695597, 10487.790244195226, 10626.36241825493, 10719.785604288027, 10976.615818446317, 11845.5854045108, 12232.26606403583, 12242.387880605756, 12290.0, 12610.684732881751, 10698.322632024414, 10764.3541387517, 10899.836502452312, 11077.667669969915, 11157.0, 10966.0, 11332.716682083013, 11390.772184556428, 11398.715032124263, 11697.264781644015, 11097.445127743742, 11195.776084478428, 11196.832047519456, 11198.496067677137, 11606.071514278668, 9877.05958800781, 10302.742431439274, 10306.072453479223, 10414.479276036016, 10705.19711021661, 8890.822183556422, 9105.77235569116, 9173.633054677739, 9251.444913305224, 9256.861147082935], "unit": "score", "name": "yui.html", "value": 6019.19025335683}], "name": "dromaeo_css", "value": 6849.996763931757}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 103.0, 111.0, 110.0, 111.0, 100.0, 109.0, 110.0, 101.0], "unit": "score", "name": "ai-astar", "value": 107.6}, {"lowerIsBetter": true, "replicates": [145.0, 124.0, 154.0, 163.0, 129.0, 153.0, 150.0, 134.0, 177.0, 129.0], "unit": "score", "name": "audio-beat-detection", "value": 145.8}, {"lowerIsBetter": true, "replicates": [160.0, 163.0, 159.0, 162.0, 159.0, 159.0, 161.0, 163.0, 162.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.9}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 80.0, 94.0, 72.0, 71.0, 71.0, 113.0, 90.0, 71.0], "unit": "score", "name": "audio-fft", "value": 80.5}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 139.0, 95.0, 97.0, 97.0, 142.0, 96.0, 96.0, 140.0], "unit": "score", "name": "audio-oscillator", "value": 109.4}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0, 109.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 58.0, 57.0, 56.0, 57.0, 57.0, 67.0, 55.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 57.8}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 68.0, 69.0, 70.0, 69.0, 69.0, 69.0, 70.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.9}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 86.0, 87.0, 87.0, 86.0, 88.0, 94.0, 95.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.6}, {"lowerIsBetter": true, "replicates": [116.0, 133.0, 117.0, 117.0, 117.0, 124.0, 124.0, 114.0, 118.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.6}, {"lowerIsBetter": true, "replicates": [142.0, 142.0, 158.0, 150.0, 152.0, 143.0, 152.0, 153.0, 154.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 148.8}, {"lowerIsBetter": true, "replicates": [70.0, 68.0, 70.0, 71.0, 67.0, 67.0, 67.0, 69.0, 67.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.4}], "name": "kraken", "value": 1508.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.78523489932886], "unit": "score", "name": "Richards", "value": 210.39992}, {"lowerIsBetter": false, "replicates": [190.47619047619048], "unit": "score", "name": "DeltaBlue", "value": 347.1194999999999}, {"lowerIsBetter": false, "replicates": [280.50490883590464], "unit": "score", "name": "Encrypt", "value": 948.9352650000003}, {"lowerIsBetter": false, "replicates": [5263.1578947368425], "unit": "score", "name": "Decrypt", "value": 50.57439}, {"lowerIsBetter": false, "replicates": [1951.2670565302144], "unit": "score", "name": "RayTrace", "value": 379.23512187812196}, {"lowerIsBetter": false, "replicates": [797.4481658692185], "unit": "score", "name": "Earley", "value": 835.7446020000003}, {"lowerIsBetter": false, "replicates": [12500.0], "unit": "score", "name": "Boyer", "value": 53.31704}, {"lowerIsBetter": false, "replicates": [26025.641025641027], "unit": "score", "name": "RegExp", "value": 35.003364532019674}, {"lowerIsBetter": false, "replicates": [636.9426751592357], "unit": "score", "name": "Splay", "value": 127.94086999999998}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 19620.717095841108}]} 23:07:44 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "19620.72"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1508.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6850.00"}}} 23:07:44 INFO - Return code: 0 23:07:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 23:07:44 INFO - # TBPL SUCCESS # 23:07:44 INFO - Running post-action listener: _resource_record_post_action 23:07:44 INFO - Running post-run listener: _resource_record_post_run 23:07:45 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 10846208; Write bytes: 443695104; Read time: 2008; Write time: 358544 23:07:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 83955712; Read time: 20; Write time: 67364 23:07:45 INFO - run-tests - Wall time: 1216s; CPU: 13.0%; Read bytes: 9080832; Write bytes: 359739392; Read time: 1896; Write time: 291180 23:07:45 INFO - Running post-run listener: _upload_blobber_files 23:07:45 INFO - Blob upload gear active. 23:07:45 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 23:07:45 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 23:07:45 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'] 23:07:45 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 23:07:45 INFO - (blobuploader) - INFO - Open directory for files ... 23:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:07:46 INFO - (blobuploader) - INFO - Done attempting. 23:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 23:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:07:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:07:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:07:47 INFO - (blobuploader) - INFO - Done attempting. 23:07:47 INFO - (blobuploader) - INFO - Iteration through files over. 23:07:47 INFO - Return code: 0 23:07:47 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 23:07:47 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 23:07:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/7c518e5063b6cd95b4bc7dddb6aca643cdcd90b1ebf6175f753ba6b1edc8e35224bb927956224beb3f9421a87580e0c83ae8a86359b3da0b141d9d6563b6a244", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/1cb11be2c069a05986defcb0cc0b3537133954f3e7b86986badd00ca28cdb8fecfcd56bb7383b0b2b28d00c07650a0123d3f281ea9ab781c66db969d0c3a4ad2"} 23:07:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 23:07:47 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 23:07:47 INFO - Contents: 23:07:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/7c518e5063b6cd95b4bc7dddb6aca643cdcd90b1ebf6175f753ba6b1edc8e35224bb927956224beb3f9421a87580e0c83ae8a86359b3da0b141d9d6563b6a244", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/1cb11be2c069a05986defcb0cc0b3537133954f3e7b86986badd00ca28cdb8fecfcd56bb7383b0b2b28d00c07650a0123d3f281ea9ab781c66db969d0c3a4ad2"} 23:07:47 INFO - Copying logs to upload dir... 23:07:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1277.133351 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-12-30 23:07:47.425087) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-30 23:07:47.428873) ========= 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=21869f6c619b95355032545b000001da-1451542489.989706-1542306809 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/7c518e5063b6cd95b4bc7dddb6aca643cdcd90b1ebf6175f753ba6b1edc8e35224bb927956224beb3f9421a87580e0c83ae8a86359b3da0b141d9d6563b6a244", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/1cb11be2c069a05986defcb0cc0b3537133954f3e7b86986badd00ca28cdb8fecfcd56bb7383b0b2b28d00c07650a0123d3f281ea9ab781c66db969d0c3a4ad2"} build_url:https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/AXFrnE21QLSf1j842PHdJw/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/7c518e5063b6cd95b4bc7dddb6aca643cdcd90b1ebf6175f753ba6b1edc8e35224bb927956224beb3f9421a87580e0c83ae8a86359b3da0b141d9d6563b6a244", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/1cb11be2c069a05986defcb0cc0b3537133954f3e7b86986badd00ca28cdb8fecfcd56bb7383b0b2b28d00c07650a0123d3f281ea9ab781c66db969d0c3a4ad2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-30 23:07:47.455136) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 23:07:47.455491) ========= 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=21869f6c619b95355032545b000001da-1451542489.989706-1542306809 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-30 23:07:47.538821) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-30 23:07:47.539400) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-30 23:07:47.539928) ========= ========= Total master_lag: 0.22 =========