builder: b2g-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1452602604.67 results: success (0) buildid: 20160112023021 builduid: 36095ecd92584c839ae834e0c95e9d80 revision: d81230512a10957f7a6cc0fcdf9b53601b894bc3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.671180) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.671617) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.671998) ========= 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=1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006465 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.713118) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.713445) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.738608) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.738927) ========= 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=1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.010384 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.785682) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.786019) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.786397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.786701) ========= 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=1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-12 04:43:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 28.0M=0s 2016-01-12 04:43:25 (28.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099740 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.900304) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:24.900722) ========= 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=1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.204598 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:25.114680) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-12 04:43:25.115026) ========= bash -c 'python archiver_client.py mozharness --repo integration/b2g-inbound --rev d81230512a10957f7a6cc0fcdf9b53601b894bc3 --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 d81230512a10957f7a6cc0fcdf9b53601b894bc3 --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=1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-12 04:43:25,329 truncating revision to first 12 chars 2016-01-12 04:43:25,330 Setting DEBUG logging. 2016-01-12 04:43:25,330 attempt 1/10 2016-01-12 04:43:25,330 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/d81230512a10?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-12 04:43:26,200 unpacking tar archive at: b2g-inbound-d81230512a10/testing/mozharness/ program finished with exit code 0 elapsedTime=1.239792 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-12 04:43:26.367647) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:26.367983) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:26.376558) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:26.376987) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 04:43:26.377452) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-01-12 04:43:26.377735) ========= /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=1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:43:26 INFO - MultiFileLogger online at 20160112 04:43:26 in /builds/slave/test-pgo 04:43:26 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 04:43:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 04:43:26 INFO - {'append_to_log': False, 04:43:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 04:43:26 INFO - 'blob_upload_branch': 'B2G-Inbound', 04:43:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 04:43:26 INFO - 'branch': 'B2G-Inbound', 04:43:26 INFO - 'buildbot_json_path': 'buildprops.json', 04:43:26 INFO - 'config_files': ('talos/linux_config.py',), 04:43:26 INFO - 'default_actions': ('clobber', 04:43:26 INFO - 'read-buildbot-config', 04:43:26 INFO - 'download-and-extract', 04:43:26 INFO - 'populate-webroot', 04:43:26 INFO - 'create-virtualenv', 04:43:26 INFO - 'install', 04:43:26 INFO - 'run-tests'), 04:43:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:43:26 INFO - 'download_minidump_stackwalk': True, 04:43:26 INFO - 'download_symbols': 'ondemand', 04:43:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:43:26 INFO - 'tooltool.py': '/tools/tooltool.py', 04:43:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:43:26 INFO - '/tools/misc-python/virtualenv.py')}, 04:43:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:43:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:43:26 INFO - 'installer_path': 'installer.exe', 04:43:26 INFO - 'log_level': 'info', 04:43:26 INFO - 'log_name': 'talos', 04:43:26 INFO - 'log_to_console': True, 04:43:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:43:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:43:26 INFO - 'opt_config_files': (), 04:43:26 INFO - 'pip_index': False, 04:43:26 INFO - 'sps_profile': False, 04:43:26 INFO - 'sps_profile_interval': 0, 04:43:26 INFO - 'suite': 'dromaeojs', 04:43:26 INFO - 'system_bits': '32', 04:43:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:43:26 INFO - 'title': 'talos-linux64-ix-027', 04:43:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:43:26 INFO - 'use_talos_json': True, 04:43:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 04:43:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:43:26 INFO - 'work_dir': 'build'} 04:43:26 INFO - ##### 04:43:26 INFO - ##### Running clobber step. 04:43:26 INFO - ##### 04:43:26 INFO - Running pre-action listener: _resource_record_pre_action 04:43:26 INFO - Running main action method: clobber 04:43:26 INFO - rmtree: /builds/slave/test-pgo/build 04:43:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 04:43:27 INFO - Running post-action listener: _resource_record_post_action 04:43:27 INFO - ##### 04:43:27 INFO - ##### Running read-buildbot-config step. 04:43:27 INFO - ##### 04:43:27 INFO - Running pre-action listener: _resource_record_pre_action 04:43:27 INFO - Running main action method: read_buildbot_config 04:43:27 INFO - Using buildbot properties: 04:43:27 INFO - { 04:43:27 INFO - "project": "", 04:43:27 INFO - "product": "firefox", 04:43:27 INFO - "script_repo_revision": "production", 04:43:27 INFO - "builddir": "b2g-inbound_ubuntu64_hw_test-dromaeojs", 04:43:27 INFO - "repository": "", 04:43:27 INFO - "buildername": "Ubuntu HW 12.04 x64 b2g-inbound pgo talos dromaeojs", 04:43:27 INFO - "buildid": "20160112023021", 04:43:27 INFO - "pgo_build": "True", 04:43:27 INFO - "basedir": "/builds/slave/test-pgo", 04:43:27 INFO - "buildnumber": 1103, 04:43:27 INFO - "slavename": "talos-linux64-ix-027", 04:43:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:43:27 INFO - "platform": "ubuntu64_hw", 04:43:27 INFO - "scheduler": "tests-b2g-inbound-linux64-pgo-talos", 04:43:27 INFO - "branch": "b2g-inbound", 04:43:27 INFO - "repo_path": "integration/b2g-inbound", 04:43:27 INFO - "revision": "d81230512a10957f7a6cc0fcdf9b53601b894bc3", 04:43:27 INFO - "stage_platform": "linux64-pgo", 04:43:27 INFO - "builduid": "36095ecd92584c839ae834e0c95e9d80", 04:43:27 INFO - "slavebuilddir": "test" 04:43:27 INFO - } 04:43:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 04:43:27 INFO - Running post-action listener: _resource_record_post_action 04:43:27 INFO - ##### 04:43:27 INFO - ##### Running download-and-extract step. 04:43:27 INFO - ##### 04:43:27 INFO - Running pre-action listener: _resource_record_pre_action 04:43:27 INFO - Running main action method: download_and_extract 04:43:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 04:43: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')]} 04:43:27 INFO - https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:43:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/test_packages.json 04:43:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/test_packages.json 04:43:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 04:43:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 04:43:31 INFO - Downloaded 1302 bytes. 04:43:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 04:43:31 INFO - Using the following test package requirements: 04:43:31 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:43:31 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:43:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:43:31 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:43:31 INFO - u'jsshell-linux-x86_64.zip'], 04:43:31 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:43:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:43:31 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:43:31 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:43:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:43:31 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:43:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:43:31 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:43:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:43:31 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 04:43:31 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 04:43:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:43:31 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:43: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')]} 04:43:31 INFO - https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:43:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:43:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:43:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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 04:43:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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 04:43:38 INFO - Downloaded 24028582 bytes. 04:43:38 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 04:43:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:43:38 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 04:43:39 INFO - Return code: 0 04:43:39 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 04:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:39 INFO - https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:43:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:43:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:43:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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 04:43:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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 04:43:41 INFO - Downloaded 24028582 bytes. 04:43:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 04:43:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 04:43:41 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:43:43 INFO - Return code: 0 04:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:43 INFO - https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:43:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:43:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:43:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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 04:43:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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 04:43:45 INFO - Downloaded 11132285 bytes. 04:43: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 04:43:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 04:43: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 04:43:45 INFO - Return code: 0 04:43: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')]} 04:43:45 INFO - https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:43:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:43:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:43:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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 04:43:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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 04:43:48 INFO - Downloaded 55948090 bytes. 04:43:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:43:48 INFO - mkdir: /builds/slave/test-pgo/properties 04:43:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 04:43:48 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 04:43:48 INFO - Contents: 04:43:48 INFO - build_url:https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 04:43:48 INFO - Running post-action listener: _resource_record_post_action 04:43:48 INFO - Running post-action listener: set_extra_try_arguments 04:43:48 INFO - ##### 04:43:48 INFO - ##### Running populate-webroot step. 04:43:48 INFO - ##### 04:43:48 INFO - Running pre-action listener: _resource_record_pre_action 04:43:48 INFO - Running main action method: populate_webroot 04:43:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:43:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:43:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:43:48 INFO - '--tppagecycles', 04:43:48 INFO - '7'], 04:43:48 INFO - 'tests': ['tsvgm']}}, 04:43:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:43:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:43:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:43:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 04:43:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:48 INFO - 'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:43:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:48 INFO - 'tests': ['damp', 'tps']}, 04:43:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:48 INFO - 'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['damp', 'tps']}, 04:43:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:43:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:43:48 INFO - 'other': {'tests': ['a11yr', 04:43:48 INFO - 'ts_paint', 04:43:48 INFO - 'tpaint', 04:43:48 INFO - 'sessionrestore', 04:43:48 INFO - 'sessionrestore_no_auto_restore']}, 04:43:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['a11yr', 04:43:48 INFO - 'ts_paint', 04:43:48 INFO - 'tpaint', 04:43:48 INFO - 'sessionrestore', 04:43:48 INFO - 'sessionrestore_no_auto_restore']}, 04:43:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['a11yr', 04:43:48 INFO - 'ts_paint', 04:43:48 INFO - 'tpaint', 04:43:48 INFO - 'sessionrestore', 04:43:48 INFO - 'sessionrestore_no_auto_restore']}, 04:43:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['a11yr', 04:43:48 INFO - 'ts_paint', 04:43:48 INFO - 'tpaint', 04:43:48 INFO - 'sessionrestore', 04:43:48 INFO - 'sessionrestore_no_auto_restore']}, 04:43:48 INFO - 'other_l64': {'tests': ['a11yr', 04:43:48 INFO - 'ts_paint', 04:43:48 INFO - 'tpaint', 04:43:48 INFO - 'sessionrestore', 04:43:48 INFO - 'sessionrestore_no_auto_restore']}, 04:43:48 INFO - 'other_nol64': {'tests': ['a11yr', 04:43:48 INFO - 'ts_paint', 04:43:48 INFO - 'tpaint', 04:43:48 INFO - 'sessionrestore', 04:43:48 INFO - 'sessionrestore_no_auto_restore']}, 04:43:48 INFO - 'svgr': {'tests': ['tsvgx', 04:43:48 INFO - 'tsvgr_opacity', 04:43:48 INFO - 'tart', 04:43:48 INFO - 'tscrollx', 04:43:48 INFO - 'cart']}, 04:43:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['tsvgx', 04:43:48 INFO - 'tsvgr_opacity', 04:43:48 INFO - 'tart', 04:43:48 INFO - 'tscrollx', 04:43:48 INFO - 'cart']}, 04:43:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:48 INFO - 'tests': ['tp5o']}, 04:43:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:48 INFO - 'talos_options': ['--e10s'], 04:43:48 INFO - 'tests': ['tp5o']}, 04:43:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:48 INFO - 'talos_options': ['--xperf_path', 04:43:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:48 INFO - 'tests': ['tp5n']}, 04:43:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:43:48 INFO - 'talos_options': ['--e10s', 04:43:48 INFO - '--xperf_path', 04:43:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:43:48 INFO - 'tests': ['tp5n']}}, 04:43:48 INFO - 'talos.zip': {'path': '', 04:43:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:43:48 INFO - Running post-action listener: _resource_record_post_action 04:43:48 INFO - ##### 04:43:48 INFO - ##### Running create-virtualenv step. 04:43:48 INFO - ##### 04:43:48 INFO - Running pre-action listener: _resource_record_pre_action 04:43:48 INFO - Running main action method: create_virtualenv 04:43:48 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 04:43:48 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 04:43:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 04:43:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:43:48 INFO - Using real prefix '/usr' 04:43:48 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 04:43:49 INFO - Installing distribute.............................................................................................................................................................................................done. 04:43:51 INFO - Installing pip.................done. 04:43:51 INFO - Return code: 0 04:43:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 04:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:43:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 04:43:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:43:51 INFO - Using env: {'DISPLAY': ':0', 04:43:51 INFO - 'HOME': '/home/cltbld', 04:43:51 INFO - 'LANG': 'en_US.UTF-8', 04:43:51 INFO - 'LANGUAGE': 'en_US:en', 04:43:51 INFO - 'LOGNAME': 'cltbld', 04:43:51 INFO - 'MAIL': '/var/mail/cltbld', 04:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:51 INFO - 'MOZ_NO_REMOTE': '1', 04:43:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:51 INFO - 'NO_EM_RESTART': '1', 04:43:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:43:51 INFO - 'PWD': '/builds/slave/test-pgo', 04:43:51 INFO - 'SHELL': '/bin/bash', 04:43:51 INFO - 'SHLVL': '1', 04:43:51 INFO - 'TERM': 'linux', 04:43:51 INFO - 'TMOUT': '86400', 04:43:51 INFO - 'USER': 'cltbld', 04:43:51 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:51 INFO - '_': '/tools/buildbot/bin/python'} 04:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:51 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 04:43:51 INFO - Cleaning up... 04:43:51 INFO - Return code: 0 04:43:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 04:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:43:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 04:43:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:43:51 INFO - Using env: {'DISPLAY': ':0', 04:43:51 INFO - 'HOME': '/home/cltbld', 04:43:51 INFO - 'LANG': 'en_US.UTF-8', 04:43:51 INFO - 'LANGUAGE': 'en_US:en', 04:43:51 INFO - 'LOGNAME': 'cltbld', 04:43:51 INFO - 'MAIL': '/var/mail/cltbld', 04:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:51 INFO - 'MOZ_NO_REMOTE': '1', 04:43:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:51 INFO - 'NO_EM_RESTART': '1', 04:43:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:43:51 INFO - 'PWD': '/builds/slave/test-pgo', 04:43:51 INFO - 'SHELL': '/bin/bash', 04:43:51 INFO - 'SHLVL': '1', 04:43:51 INFO - 'TERM': 'linux', 04:43:51 INFO - 'TMOUT': '86400', 04:43:51 INFO - 'USER': 'cltbld', 04:43:51 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:51 INFO - '_': '/tools/buildbot/bin/python'} 04:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:51 INFO - Downloading/unpacking psutil>=0.7.1 04:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:43:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:43:53 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 04:43:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:43:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 04:43:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:53 INFO - Installing collected packages: psutil 04:43:53 INFO - Running setup.py install for psutil 04:43:53 INFO - building 'psutil._psutil_linux' extension 04:43:53 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 04:43: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 04:43:53 INFO - building 'psutil._psutil_posix' extension 04:43:53 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 04:43: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:43:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:43:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:43:53 INFO - Successfully installed psutil 04:43:53 INFO - Cleaning up... 04:43:54 INFO - Return code: 0 04:43:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 04:43: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')]} 04:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:43: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 04:43: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 04:43:54 INFO - Using env: {'DISPLAY': ':0', 04:43:54 INFO - 'HOME': '/home/cltbld', 04:43:54 INFO - 'LANG': 'en_US.UTF-8', 04:43:54 INFO - 'LANGUAGE': 'en_US:en', 04:43:54 INFO - 'LOGNAME': 'cltbld', 04:43:54 INFO - 'MAIL': '/var/mail/cltbld', 04:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:54 INFO - 'MOZ_NO_REMOTE': '1', 04:43:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:54 INFO - 'NO_EM_RESTART': '1', 04:43:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:43:54 INFO - 'PWD': '/builds/slave/test-pgo', 04:43:54 INFO - 'SHELL': '/bin/bash', 04:43:54 INFO - 'SHLVL': '1', 04:43:54 INFO - 'TERM': 'linux', 04:43:54 INFO - 'TMOUT': '86400', 04:43:54 INFO - 'USER': 'cltbld', 04:43:54 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:54 INFO - '_': '/tools/buildbot/bin/python'} 04:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:43:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:43:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:43: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) 04:43:56 INFO - Installing collected packages: mozsystemmonitor 04:43:56 INFO - Running setup.py install for mozsystemmonitor 04:43:56 INFO - Successfully installed mozsystemmonitor 04:43:56 INFO - Cleaning up... 04:43:56 INFO - Return code: 0 04:43:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 04:43: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')]} 04:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:43: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 04:43: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 04:43:56 INFO - Using env: {'DISPLAY': ':0', 04:43:56 INFO - 'HOME': '/home/cltbld', 04:43:56 INFO - 'LANG': 'en_US.UTF-8', 04:43:56 INFO - 'LANGUAGE': 'en_US:en', 04:43:56 INFO - 'LOGNAME': 'cltbld', 04:43:56 INFO - 'MAIL': '/var/mail/cltbld', 04:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:56 INFO - 'MOZ_NO_REMOTE': '1', 04:43:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:56 INFO - 'NO_EM_RESTART': '1', 04:43:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:43:56 INFO - 'PWD': '/builds/slave/test-pgo', 04:43:56 INFO - 'SHELL': '/bin/bash', 04:43:56 INFO - 'SHLVL': '1', 04:43:56 INFO - 'TERM': 'linux', 04:43:56 INFO - 'TMOUT': '86400', 04:43:56 INFO - 'USER': 'cltbld', 04:43:56 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:56 INFO - '_': '/tools/buildbot/bin/python'} 04:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:58 INFO - Downloading blobuploader-1.2.4.tar.gz 04:43:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:43:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:43:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:43:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 04:43:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43: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 04:43:58 INFO - Downloading docopt-0.6.1.tar.gz 04:43:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:43:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 04:43:58 INFO - Installing collected packages: blobuploader, requests, docopt 04:43:58 INFO - Running setup.py install for blobuploader 04:43:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:43:58 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 04:43:58 INFO - Running setup.py install for requests 04:43:59 INFO - Running setup.py install for docopt 04:43:59 INFO - Successfully installed blobuploader requests docopt 04:43:59 INFO - Cleaning up... 04:43:59 INFO - Return code: 0 04:43:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:43: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')]} 04:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:43:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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')]} 04:43:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:43:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:43: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 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:43: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 04:43: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 04:43:59 INFO - Using env: {'DISPLAY': ':0', 04:43:59 INFO - 'HOME': '/home/cltbld', 04:43:59 INFO - 'LANG': 'en_US.UTF-8', 04:43:59 INFO - 'LANGUAGE': 'en_US:en', 04:43:59 INFO - 'LOGNAME': 'cltbld', 04:43:59 INFO - 'MAIL': '/var/mail/cltbld', 04:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:43:59 INFO - 'MOZ_NO_REMOTE': '1', 04:43:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:43:59 INFO - 'NO_EM_RESTART': '1', 04:43:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:43:59 INFO - 'PWD': '/builds/slave/test-pgo', 04:43:59 INFO - 'SHELL': '/bin/bash', 04:43:59 INFO - 'SHLVL': '1', 04:43:59 INFO - 'TERM': 'linux', 04:43:59 INFO - 'TMOUT': '86400', 04:43:59 INFO - 'USER': 'cltbld', 04:43:59 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:43:59 INFO - '_': '/tools/buildbot/bin/python'} 04:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:43:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:43:59 INFO - Running setup.py (path:/tmp/pip-n4dna8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:43:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:43:59 INFO - Running setup.py (path:/tmp/pip-qx8Hqh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:43:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:43:59 INFO - Running setup.py (path:/tmp/pip-9SuU6g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:43:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:43:59 INFO - Running setup.py (path:/tmp/pip-NGKxcj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:43:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:43:59 INFO - Running setup.py (path:/tmp/pip-eYzMk9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:44:00 INFO - Running setup.py (path:/tmp/pip-7gOndS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:44:00 INFO - Running setup.py (path:/tmp/pip-xUTaIW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:44:00 INFO - Running setup.py (path:/tmp/pip-kbVF95-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:44:00 INFO - Running setup.py (path:/tmp/pip-3VTyks-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:44:00 INFO - Running setup.py (path:/tmp/pip-2vRh_v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:44:00 INFO - Running setup.py (path:/tmp/pip-Xtq81M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:44:00 INFO - Running setup.py (path:/tmp/pip-h74FJ4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:44:00 INFO - Running setup.py (path:/tmp/pip-ptq2kH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:44:00 INFO - Running setup.py (path:/tmp/pip-FQCvoB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:44:00 INFO - Running setup.py (path:/tmp/pip-5my0NO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:44:00 INFO - Running setup.py (path:/tmp/pip-WwLabo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:44:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:44:00 INFO - Running setup.py (path:/tmp/pip-4rjJWk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:44:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:44:00 INFO - Running setup.py install for manifestparser 04:44:01 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Running setup.py install for mozcrash 04:44:01 INFO - Running setup.py install for mozdebug 04:44:01 INFO - Running setup.py install for mozdevice 04:44:01 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Running setup.py install for mozfile 04:44:01 INFO - Running setup.py install for mozhttpd 04:44:01 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Running setup.py install for mozinfo 04:44:01 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Running setup.py install for mozInstall 04:44:01 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Running setup.py install for mozleak 04:44:01 INFO - Running setup.py install for mozlog 04:44:01 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 04:44:01 INFO - Running setup.py install for moznetwork 04:44:02 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 04:44:02 INFO - Running setup.py install for mozprocess 04:44:02 INFO - Running setup.py install for mozprofile 04:44:02 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 04:44:02 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 04:44:02 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 04:44:02 INFO - Running setup.py install for mozrunner 04:44:02 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 04:44:02 INFO - Running setup.py install for mozscreenshot 04:44:02 INFO - Running setup.py install for moztest 04:44:02 INFO - Running setup.py install for mozversion 04:44:02 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 04:44:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:44:02 INFO - Cleaning up... 04:44:02 INFO - Return code: 0 04:44:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:44: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')]} 04:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:44: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 04:44: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 04:44:02 INFO - Using env: {'DISPLAY': ':0', 04:44:02 INFO - 'HOME': '/home/cltbld', 04:44:02 INFO - 'LANG': 'en_US.UTF-8', 04:44:02 INFO - 'LANGUAGE': 'en_US:en', 04:44:02 INFO - 'LOGNAME': 'cltbld', 04:44:02 INFO - 'MAIL': '/var/mail/cltbld', 04:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:02 INFO - 'MOZ_NO_REMOTE': '1', 04:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:44:02 INFO - 'NO_EM_RESTART': '1', 04:44:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:44:02 INFO - 'PWD': '/builds/slave/test-pgo', 04:44:02 INFO - 'SHELL': '/bin/bash', 04:44:02 INFO - 'SHLVL': '1', 04:44:02 INFO - 'TERM': 'linux', 04:44:02 INFO - 'TMOUT': '86400', 04:44:02 INFO - 'USER': 'cltbld', 04:44:02 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:02 INFO - '_': '/tools/buildbot/bin/python'} 04:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:44:02 INFO - Running setup.py (path:/tmp/pip-zrj_35-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 04:44:02 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)) 04:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:44:02 INFO - Running setup.py (path:/tmp/pip-4BEGJq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:44:03 INFO - Running setup.py (path:/tmp/pip-iQ6pJZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:44:03 INFO - Running setup.py (path:/tmp/pip-z7Robd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 04:44:03 INFO - Running setup.py (path:/tmp/pip-MXMy4k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:44:03 INFO - Running setup.py (path:/tmp/pip-WJOTJ_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:44:03 INFO - Running setup.py (path:/tmp/pip-1ewo2t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:44:03 INFO - Running setup.py (path:/tmp/pip-kWMWw4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 04:44:03 INFO - Running setup.py (path:/tmp/pip-hbf33D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 04:44:03 INFO - Running setup.py (path:/tmp/pip-uXkun5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:44:03 INFO - Running setup.py (path:/tmp/pip-1GhMm8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:44:03 INFO - Running setup.py (path:/tmp/pip-oP7W98-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 04:44: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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:44:03 INFO - Running setup.py (path:/tmp/pip-BJ_FL_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:44:03 INFO - Running setup.py (path:/tmp/pip-9eB0rs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 04:44:03 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)) 04:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:44:03 INFO - Running setup.py (path:/tmp/pip-BXcOQi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 04:44: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)) 04:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 04:44:04 INFO - Running setup.py (path:/tmp/pip-1c9uze-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 04:44: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)) 04:44:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 04:44:04 INFO - Running setup.py (path:/tmp/pip-_yb_wk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:05 INFO - Downloading blessings-1.5.1.tar.gz 04:44: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 04:44:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 04:44:05 INFO - Installing collected packages: blessings 04:44:05 INFO - Running setup.py install for blessings 04:44:06 INFO - Successfully installed blessings 04:44:06 INFO - Cleaning up... 04:44:06 INFO - Return code: 0 04:44:06 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 04:44:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:44:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:44:06 INFO - Reading from file tmpfile_stdout 04:44:06 INFO - Current package versions: 04:44:06 INFO - argparse == 1.2.1 04:44:06 INFO - blessings == 1.5.1 04:44:06 INFO - blobuploader == 1.2.4 04:44:06 INFO - docopt == 0.6.1 04:44:06 INFO - manifestparser == 1.1 04:44:06 INFO - mozInstall == 1.12 04:44:06 INFO - mozcrash == 0.16 04:44:06 INFO - mozdebug == 0.1 04:44:06 INFO - mozdevice == 0.47 04:44:06 INFO - mozfile == 1.2 04:44:06 INFO - mozhttpd == 0.7 04:44:06 INFO - mozinfo == 0.9 04:44:06 INFO - mozleak == 0.1 04:44:06 INFO - mozlog == 3.1 04:44:06 INFO - moznetwork == 0.27 04:44:06 INFO - mozprocess == 0.22 04:44:06 INFO - mozprofile == 0.28 04:44:06 INFO - mozrunner == 6.11 04:44:06 INFO - mozscreenshot == 0.1 04:44:06 INFO - mozsystemmonitor == 0.0 04:44:06 INFO - moztest == 0.7 04:44:06 INFO - mozversion == 1.4 04:44:06 INFO - psutil == 3.1.1 04:44:06 INFO - requests == 1.2.3 04:44:06 INFO - wsgiref == 0.1.2 04:44:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 04:44: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')]} 04:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:44: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 04:44: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 04:44:06 INFO - Using env: {'DISPLAY': ':0', 04:44:06 INFO - 'HOME': '/home/cltbld', 04:44:06 INFO - 'LANG': 'en_US.UTF-8', 04:44:06 INFO - 'LANGUAGE': 'en_US:en', 04:44:06 INFO - 'LOGNAME': 'cltbld', 04:44:06 INFO - 'MAIL': '/var/mail/cltbld', 04:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:06 INFO - 'MOZ_NO_REMOTE': '1', 04:44:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:44:06 INFO - 'NO_EM_RESTART': '1', 04:44:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:44:06 INFO - 'PWD': '/builds/slave/test-pgo', 04:44:06 INFO - 'SHELL': '/bin/bash', 04:44:06 INFO - 'SHLVL': '1', 04:44:06 INFO - 'TERM': 'linux', 04:44:06 INFO - 'TMOUT': '86400', 04:44:06 INFO - 'USER': 'cltbld', 04:44:06 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:06 INFO - '_': '/tools/buildbot/bin/python'} 04:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44: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)) 04:44:06 INFO - Cleaning up... 04:44:06 INFO - Return code: 0 04:44:06 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 04:44: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')]} 04:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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')]} 04:44:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44: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 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:44: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 04:44: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 04:44:06 INFO - Using env: {'DISPLAY': ':0', 04:44:06 INFO - 'HOME': '/home/cltbld', 04:44:06 INFO - 'LANG': 'en_US.UTF-8', 04:44:06 INFO - 'LANGUAGE': 'en_US:en', 04:44:06 INFO - 'LOGNAME': 'cltbld', 04:44:06 INFO - 'MAIL': '/var/mail/cltbld', 04:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:06 INFO - 'MOZ_NO_REMOTE': '1', 04:44:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:44:06 INFO - 'NO_EM_RESTART': '1', 04:44:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:44:06 INFO - 'PWD': '/builds/slave/test-pgo', 04:44:06 INFO - 'SHELL': '/bin/bash', 04:44:06 INFO - 'SHLVL': '1', 04:44:06 INFO - 'TERM': 'linux', 04:44:06 INFO - 'TMOUT': '86400', 04:44:06 INFO - 'USER': 'cltbld', 04:44:06 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:06 INFO - '_': '/tools/buildbot/bin/python'} 04:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:06 INFO - Downloading/unpacking jsonschema 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:44: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 04:44:08 INFO - Downloading/unpacking functools32 (from jsonschema) 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:08 INFO - Downloading functools32-3.2.3-2.tar.gz 04:44: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 04:44:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 04:44:08 INFO - warning: no files found matching '*.txt' 04:44:08 INFO - no previously-included directories found matching 'build' 04:44:08 INFO - no previously-included directories found matching 'dist' 04:44:08 INFO - no previously-included directories found matching '.git*' 04:44:08 INFO - Installing collected packages: jsonschema, functools32 04:44:08 INFO - Running setup.py install for functools32 04:44:08 INFO - warning: no files found matching '*.txt' 04:44:08 INFO - no previously-included directories found matching 'build' 04:44:08 INFO - no previously-included directories found matching 'dist' 04:44:08 INFO - no previously-included directories found matching '.git*' 04:44:08 INFO - Successfully installed jsonschema functools32 04:44:08 INFO - Cleaning up... 04:44:08 INFO - Return code: 0 04:44:08 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 04:44:08 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')]} 04:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:44:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:08 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')]} 04:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:44:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:44:08 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 0x7f43badf8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x223b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2308360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, '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': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', '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 04:44:08 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 04:44:08 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 04:44:08 INFO - Using env: {'DISPLAY': ':0', 04:44:08 INFO - 'HOME': '/home/cltbld', 04:44:08 INFO - 'LANG': 'en_US.UTF-8', 04:44:08 INFO - 'LANGUAGE': 'en_US:en', 04:44:08 INFO - 'LOGNAME': 'cltbld', 04:44:08 INFO - 'MAIL': '/var/mail/cltbld', 04:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:08 INFO - 'MOZ_NO_REMOTE': '1', 04:44:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:44:08 INFO - 'NO_EM_RESTART': '1', 04:44:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:44:08 INFO - 'PWD': '/builds/slave/test-pgo', 04:44:08 INFO - 'SHELL': '/bin/bash', 04:44:08 INFO - 'SHLVL': '1', 04:44:08 INFO - 'TERM': 'linux', 04:44:08 INFO - 'TMOUT': '86400', 04:44:08 INFO - 'USER': 'cltbld', 04:44:08 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:08 INFO - '_': '/tools/buildbot/bin/python'} 04:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:44:09 INFO - Downloading/unpacking flake8 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:44: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 04:44:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:44: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 04:44:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44: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 04:44:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:44:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 04:44:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:44:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:44:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:44:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:44:11 INFO - no previously-included directories found matching 'docs/_build' 04:44:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:44:11 INFO - Running setup.py install for pep8 04:44:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:44:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:44:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:44:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:44:11 INFO - no previously-included directories found matching 'docs/_build' 04:44:11 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 04:44:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:44:11 INFO - Cleaning up... 04:44:11 INFO - Return code: 0 04:44:11 INFO - Running post-action listener: _resource_record_post_action 04:44:11 INFO - Running post-action listener: _start_resource_monitoring 04:44:11 INFO - Starting resource monitoring. 04:44:11 INFO - ##### 04:44:11 INFO - ##### Running install step. 04:44:11 INFO - ##### 04:44:11 INFO - Running pre-action listener: _resource_record_pre_action 04:44:11 INFO - Running main action method: install 04:44:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 04:44:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 04:44:12 INFO - Reading from file tmpfile_stdout 04:44:12 INFO - Detecting whether we're running mozinstall >=1.0... 04:44:12 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 04:44:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 04:44:12 INFO - Reading from file tmpfile_stdout 04:44:12 INFO - Output received: 04:44:12 INFO - Usage: mozinstall [options] installer 04:44:12 INFO - Options: 04:44:12 INFO - -h, --help show this help message and exit 04:44:12 INFO - -d DEST, --destination=DEST 04:44:12 INFO - Directory to install application into. [default: 04:44:12 INFO - "/builds/slave/test-pgo"] 04:44:12 INFO - --app=APP Application being installed. [default: firefox] 04:44:12 INFO - mkdir: /builds/slave/test-pgo/build/application 04:44: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'] 04:44: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 04:44:28 INFO - Reading from file tmpfile_stdout 04:44:28 INFO - Output received: 04:44:28 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 04:44:28 INFO - Running post-action listener: _resource_record_post_action 04:44:28 INFO - ##### 04:44:28 INFO - ##### Running run-tests step. 04:44:28 INFO - ##### 04:44:28 INFO - Running pre-action listener: _resource_record_pre_action 04:44:28 INFO - Running main action method: run_tests 04:44:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 04:44:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 04:44:28 INFO - Python 2.7.3 04:44:28 INFO - Return code: 0 04:44:28 INFO - grabbing minidump binary from tooltool 04:44:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2397540>, '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 0x2398440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23988f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 04:44:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 04:44:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:44:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:44:28 INFO - Return code: 0 04:44:28 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 04:44:28 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 04:44:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 04:44:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 04:44:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 04:44:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 04:44:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 04:44:29 INFO - Return code: 0 04:44: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/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 04:44: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/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 04:44:29 INFO - Using env: {'DISPLAY': ':0', 04:44:29 INFO - 'HOME': '/home/cltbld', 04:44:29 INFO - 'LANG': 'en_US.UTF-8', 04:44:29 INFO - 'LANGUAGE': 'en_US:en', 04:44:29 INFO - 'LOGNAME': 'cltbld', 04:44:29 INFO - 'MAIL': '/var/mail/cltbld', 04:44:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:44:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 04:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:44:29 INFO - 'MOZ_NO_REMOTE': '1', 04:44:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 04:44:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:44:29 INFO - 'NO_EM_RESTART': '1', 04:44:29 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 04:44:29 INFO - 'PWD': '/builds/slave/test-pgo', 04:44:29 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 04:44:29 INFO - 'SHELL': '/bin/bash', 04:44:29 INFO - 'SHLVL': '1', 04:44:29 INFO - 'TERM': 'linux', 04:44:29 INFO - 'TMOUT': '86400', 04:44:29 INFO - 'USER': 'cltbld', 04:44:29 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490', 04:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:44:29 INFO - '_': '/tools/buildbot/bin/python'} 04:44: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/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 04:44:29 INFO - mozversion application_buildid: 20160112023021 04:44:29 INFO - mozversion application_changeset: d81230512a10957f7a6cc0fcdf9b53601b894bc3 04:44:29 INFO - mozversion application_display_name: Nightly 04:44:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:44:29 INFO - mozversion application_name: Firefox 04:44:29 INFO - mozversion application_remotingname: firefox 04:44:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/b2g-inbound 04:44:29 INFO - mozversion application_vendor: Mozilla 04:44:29 INFO - mozversion application_version: 46.0a1 04:44:29 INFO - mozversion platform_buildid: 20160112023021 04:44:29 INFO - mozversion platform_changeset: d81230512a10957f7a6cc0fcdf9b53601b894bc3 04:44:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/b2g-inbound 04:44:29 INFO - mozversion platform_version: 46.0a1 04:44:29 INFO - using testdate: 1452602669 04:44:29 INFO - actual date: 1452602669 04:44:29 INFO - starting webserver on 'localhost:41029' 04:44:29 INFO - SUITE-START | Running 3 tests 04:44:29 INFO - TEST-START | dromaeo_css 04:44:29 INFO - operating with platform_type : linux_ 04:44:29 INFO - Initialising browser for dromaeo_css test... 04:44:29 INFO - TEST-INFO | started process 26060 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTwe57d/profile http://localhost:41029/getInfo.html) 04:44:33 INFO - PROCESS | 26060 | __metrics Screen width/height:1600/1200 04:44:33 INFO - PROCESS | 26060 | colorDepth:24 04:44:33 INFO - PROCESS | 26060 | Browser inner width/height: 1024/697 04:44:33 INFO - PROCESS | 26060 | __metrics 04:44:33 INFO - PROCESS | 26060 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:44:33 INFO - PROCESS | 26060 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:44:34 INFO - TEST-INFO | 26060: exit 0 04:44:34 INFO - Browser initialized. 04:44:34 INFO - Running cycle 1/1 for dromaeo_css test... 04:44:34 INFO - TEST-INFO | started process 26153 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpTwe57d/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:44:46 INFO - PROCESS | 26153 | RSS: Main: 149127168 04:44:46 INFO - PROCESS | 26153 | 04:45:37 INFO - PROCESS | 26153 | 1452602737557 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] 04:47:45 INFO - PROCESS | 26153 | Cycle 1(1): loaded http://localhost:41029/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41029/tests/dromaeo/cssquery-ext.html) 04:47:46 INFO - PROCESS | 26153 | RSS: Main: 270213120 04:47:46 INFO - PROCESS | 26153 | 04:50:49 INFO - PROCESS | 26153 | Cycle 1(1): loaded http://localhost:41029/tests/dromaeo/cssquery-ext.html (next: http://localhost:41029/tests/dromaeo/cssquery-jquery.html) 04:50:50 INFO - PROCESS | 26153 | RSS: Main: 241123328 04:50:50 INFO - PROCESS | 26153 | 04:53:51 INFO - PROCESS | 26153 | Cycle 1(1): loaded http://localhost:41029/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41029/tests/dromaeo/cssquery-mootools.html) 04:53:52 INFO - PROCESS | 26153 | RSS: Main: 233926656 04:53:52 INFO - PROCESS | 26153 | 04:56:54 INFO - PROCESS | 26153 | Cycle 1(1): loaded http://localhost:41029/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41029/tests/dromaeo/cssquery-prototype.html) 04:56:54 INFO - PROCESS | 26153 | RSS: Main: 218755072 04:56:54 INFO - PROCESS | 26153 | 04:59:57 INFO - PROCESS | 26153 | Cycle 1(1): loaded http://localhost:41029/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41029/tests/dromaeo/cssquery-yui.html) 04:59:58 INFO - PROCESS | 26153 | RSS: Main: 221163520 04:59:58 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | Cycle 1(1): loaded http://localhost:41029/tests/dromaeo/cssquery-yui.html (next: http://localhost:41029/tests/dromaeo/cssquery-dojo.html) 05:03:00 INFO - PROCESS | 26153 | RSS: Main: 292388864 05:03:00 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | __start_tp_report 05:03:00 INFO - PROCESS | 26153 | _x_x_mozilla_page_load 05:03:00 INFO - PROCESS | 26153 | _x_x_mozilla_page_load_details 05:03:00 INFO - PROCESS | 26153 | |i|pagename|runs| 05:03:00 INFO - PROCESS | 26153 | |0;dojo.html;3111.9688803111967;3165.6834316568334;3204.7275981541566;3221.7583681223914;3232.4989626607885;5021.623378246637;5079.187330027196;5141.922871156931;5152.40747314059;5209.296744939432;5112.335396398463;5116.232565115234;5163.664361816489;5173.9482605173935;5246.501582349681;4499.7750112494405;4648.721076735406;4679.134360143385;4685.2035154023815;4810.711357318554;4766.571008609225;4772.856814295576;4799.304100905367;4848.224284114543;4872.171730805754;4613.930791038137;4684.039771846763;4721.48063712993;4781.187198176283;4797.928031079537;4622.2835460503675;4633.745144017078;4642;4647.512011238805;4658.97502549442;4593.058423023305;4608.861734147981;4641.930371044437;4642.512536183686;4654.86035418938;4956.925646115311;5022.246663000543;5053.469385714484;5089.312942752718;5122.436531981479;5528.944710552923;5533.6403133796175;5549.639273447254;5561.888762224773;5569.526590239834;5439.129739241703;5561.499465048125;5564.916526252109;5569.081101618269;5570.108782594765;5132.717700526469;5149.150390185615;5152.330197074356;5271.103912334912;5306;5692.288463942007;5703.885922281531;5705.343885453226;5714.7428365723645;5764.423557644286;5343.064963631349;5471.972640136773;5474.589405794582;5474.671519708831;5477.67133971963;5521.3926468088475;5550.139728342114;5586.385497595261;5667.979763642584;5714.999875021855;5513.917291240635;5522.530584900329;5525.530329921921;5525.778968841281;5529.364123125892;5407.567394608422;5462.0987537056735;5509;5513.807016754395;5558;4314.870553883388;4326.783660816946;4361.214981303332;4421.801018954155;4454.020115574568;3863.459115723801;3866.2654095721723;3871.5934826842777;3902.2585708715255;3911.1395492992065;5120.411152717411;5128.12821820284;5173.486503840056;5175.844724658266;5181.766820493087;5461.672299662033;5474.151506516497;5481.260029895992;5615.438456154352;5701.971490142606;5193.558547523503;5201.323827902348;5206.140986737221;5242.1088414969545;5260.395054568697;4772.976135119372;4803.95196048042;4822.614190864722;4826.058918510927;4827.493113223061;5649.830505084854;5742.224799652077;5770.855728606734;5771.711414429262;5825.271841019958;5551.139573366135;5559.52744016747;5560.527355174693;5571.1086226203615;5606;5785.652860828363;5814.069748840166;5827.79602713903;5831.825045248649;5834.18321434991;5423.403425623257;5530.05988981866;5530.5022547970875;5568.415316391721;5682.318121825408;5586.832395028156;5615.691136987505;5628.296462942132;5635.9436405635415;5636;5485.122380419114;5486.533644640091;5496.230527726201;5502.80740174092;5541.2796336476;5332.120200166929;5401.216823560483;5448.128299472065;5459.754311055933;5498.1752737089755;5273.446288139843;5279.736013199248;5284.154535274338;5284.41871394154;5287.180487024518;5305.204219367126;5356.517913387814;5399.190121481652;5443.755031023693;5453.10023846061;5280.788768449373;5304.124819404755;5333.706646134345;5353.625246232727;5405.324334458192 05:03:00 INFO - PROCESS | 26153 | |1;ext.html;534.6952237224783;550.257152843661;557.9163125531172;564.3594520219551;578.2425023219582;28116.437671246564;28794.13617591473;28829.99095031677;35234.8238258809;35981;37899;38385;38411.80794096032;38528.42207366892;38528.80735596318;8541.87187192191;8865.556722163898;8871.911280887172;8878.822423551524;8881.911180888173;7495.437842161832;7676.501027433227;10066.647667331645;10276.280660353777;10278.640247591336;10091.444970526618;10159.898401016006;10160.644377446792;10169.237307201953;10178.338408003494;7435.256474352575;7615.048118985127;9948.751281217956;10029;10029.197664186884;7369.336759691653;10095.39427634337;10352.896471035268;10369.688909332732;10371.740706482324;34615.826920865235;35019.29961400783;35086.649133508596;35091.82454087713;35198.64801351979;3137.8274194919272;3171.5718378019133;3189.3142974260413;3515.2775140181384;3584.193556449804;27322;27484.175474735788;27711.445771084665;28108.43783124326;29792.851035744898;3966.5636779954184;3973.384125460558;3995.840166393341;4028.9194216115516;4106.2198182345255;246316;247318;248405;248857;250324.99999999817;50727.239091413656;51786.48213517892;56548.43451565432;56937;56967;61456;61970.38029619646;62091.689541552005;62204.377956220764;62228.688856555425;13991.930040349733;14205.857941420454;14207.573772786833;14213.928930355281;14221;9717.222622190207;9743.12311891919;9744.415335079777;9746.95126524377;9754.902450975542;19218.231270749293;19360.903195483934;19431.514212144808;19499.512512187026;19532.414027579194;7179.317964793337;7722.382209423233;7784.610769461504;7851.136374998746;7878.369730421553;908.7637214324191;970.1462712812821;1021.0147207944369;1055.2507719519074;1089.4334945828284;26922.576968072575;27043.864780676366;27456.764445599994;27967.160985170478;28038;1026.4970164619283;1036.3781730961364;1094.6715985204407;1165.650304908524;1169.9590514331958;13827.239501827496;14163.221022843842;14181.432742690384;14626.926865365818;14668.55994320172;296854;302967;304193;305399;305422;49291;49564.50435495599;49674.75162624164;49685.50314496881;49740;1310.7050913544567;1317.8484474285387;1330.8336457942758;1332.460353556811;1351.540476238064;26970.460590788465;27527;27949.860250698617;28189.43621127525;28212.576811348255;43456;43862.34206486969;43867.12265754603;43867.12265754731;43907.78046109749;290067;292234;292363;293181;294094;27805.44389112166;28337.29156771097;30982.97221361299;30988;31037.68962310348;35208.823955880056;35725.1068723284;35990.28019439649;35993.460098098025;36515.817420912725;30821.38357232798;31125.844370778002;39244;39868.2026359477;40435.98910027273;29553.52751516456;35941.46087808633;36435.45346819853;37116.50817405662;38951.41572876466;33799.4930076054;35519.11202219862;35548.64451355454;35632.64367356293;35672.821635892695;32920.506192407614;34222.82888585541;35430.29139417249;35505;35585.28829423449;25541.61687574725;25943.48113037691;26064.34839128961;26076.8696156518;26153.215403537924 05:03:00 INFO - PROCESS | 26153 | |2;jquery.html;368.56693385272314;369.1822612912397;369.61744594344856;370.42768922015483;374.6403452685422;13047.41286642101;13351.265680387574;15786.763198552017;16008;16534.917325413386;9895.851562226555;12938.417771200295;13565;13582.932085339584;13587.660308492292;4702.341672165891;4893.926591101136;4899.975500122512;4912.0667073256145;4922.409310882681;3742.8877133686033;3858.344081506151;4808.735519546423;4861.829835955742;5211;5371.946280537203;5411.107167317388;5416.645838540365;5417.241586177938;5421.891562168754;5350.170723537858;6004.519638428916;6014.609050411709;6017.548683848685;6058.939410605925;5193.065248255312;5389.703566303851;5401.891962160774;5406.972965135149;5434.239206511092;19381.709274360754;19841.30555430553;19954.201831926704;20104.195832166697;20127;1992.2230330171244;2034.4303594993278;2038.7961203879643;2048.068129001301;2083.260442542891;8502.574871256413;8514.616842242114;8687.3050155988;8865.157810008039;8918.955405222998;1719.2521253254874;1763.5414792154004;1770.8317709817675;1822.1435925115306;1830.8352248297583;52139.21791173136;54925.45074549283;56020;56295.71852140754;56811.14783278254;9661.661841835503;9692.22462203022;9861.506924653739;9891.60433582663;9920.503974801375;12463.065270104596;12530;12535;12536.373181340896;12551.435185416678;6955.339242771928;6969.616671083139;7050.224475307712;7185.568865868065;7289.307515785992;6659.101021362151;6668.833279167962;6697.598144111272;6799.4900382471515;6843.076184715099;4302.139572085595;4313.158934007901;4366.366876802909;4431.756253406029;4436.911261774811;1696.9321227150776;1743.668702946436;1753.0708724376102;1760.9823901761074;1802.97295540567;901.4861528928448;906.9365144439957;987.0770829274651;1019.6023550815188;1035.0736091198485;15696.76454853178;15936;16177.11025893587;16308.103054332125;16494.092824894506;867.2888231649987;883.9116088391238;886.7339798060556;901.6889173235223;902.1249388093539;8806.86789698155;9843.803123937625;9847.45838978863;9937.45344006072;10068.798624027479;482669;486391;497206;497319;501045;67144.6642766783;67611.3238867605;68403;69187.65406172936;69928;1121.0471099565468;1132.1565433751907;1135.4436326200102;1141.372245265101;1148.1274231584057;13864;14613.634659133606;14714.926425367805;15065.698686026437;15071.4724984625;29523;29689.663965122054;29988.550171747876;32000;33723.494147588295;40062.1987560253;40994.79502602468;41834.790826045675;42638.1472370557;44159.55840441555;7008.719651214098;7315.231900650568;7315.890261646186;7374.483786134919;7421.925780742124;9294.349395542247;11052.889471105185;11064.28082174656;11295.943520282675;11398.145139114766;5368.812091576776;6597.175353080865;8522.914770852212;8674.739757807276;9337.859932100888;9626.518674066408;9969.850452243078;10171.898281017095;10216.744581385525;10234.437105958947;7677.270659287359;9084.682036128705;9287.117723816227;9576.186024187744;9713.174380177481;6629.801105966829;6813.488988325995;6826.692798824164;6908.689108990007;6922.757703480354;7968.601569921597;8544.829103417773;8721.258693011165;9122.726318210463;9209.953950230207 05:03:00 INFO - PROCESS | 26153 | |3;mootools.html;1238.6779437346288;1444.0830072903707;1458.1688437590574;1603.0942517477624;1756.9560760980983;6920.619365934872;7134.393576545994;7409.70361185552;7545.358644515223;7564.281393267631;7632.3894088472925;7632.6183690815515;7717.035370578677;7725.884111738328;7727.768166955;1984.8213660770527;2032.2175962254564;2263.7849404306608;2291.793738563529;2293.8738369389675;2260.604394231011;2314.594945884472;2316.8262380321457;2317.8261630377706;2318.026428899866;2211.060299372768;2232.520008198237;2255.76314486979;2258.9209377671787;2259.141526220042;2239.261043855524;2250.437390652337;2262.162999690109;2265.184533567914;2269.035659844568;2259.2205689037255;2263.9660405093937;2271.057511132878;2271.3867255840996;2278.487340348425;7809.414293927921;7868.803279917991;7878.369730421553;7893.289603935674;7964.084130325029;4660.86017419478;4786.305985632099;4789.137955168068;4796.2326027835725;4800.735959522225;3027.2734543709353;3105.844707764603;3119.3605310911435;3126.98436507816;3150.401423729484;977.3451787302524;980.7940332530177;1034.9741256468574;1041.3907863899628;1082.9566817327297;10308.742281442948;10467.79064418715;10478.685639430829;10493.05562499371;10518;4177.436046133794;4250.256205164084;4335.783210839445;4353.586409291112;4354.4992325882295;9246.26029917605;9252.814943701089;9269.397489163182;9314;9320.72037838866;4715.221988371949;4715.481297057321;4723.244280915037;4728.030753695484;4728.621710263171;4203.306454434984;4208.873733787991;4250.872473825791;4252.043290259667;4253.766042867672;3483.703885169789;3557.5908770491205;3587.0673624857204;3704.592494825567;3707.073231692077;1236.140882086959;1242.6023672424917;1256.1772039314264;1262.3688155922039;1268.2960956669137;1331.9800202996962;1333.6199183232732;1368.6578355411148;1375.7661197596233;1387.3964825301027;7451.5901625411125;7455.254474552501;7480.3641690456925;7504.061992250969;7508.2491750825575;567.4127278266974;575.689127870945;583.4165834165834;592.2094004503949;592.8369698332993;5718.056520674126;5819.709014549256;5845.123231515222;5884.029135192731;5886.911696324559;9229.953850230706;9576.760580985392;9594.376365536218;9642.855357169648;9658.517074146264;9355.345125841126;9512.667056652985;9522.476263805556;9546.331756776959;9557;7344.449166312548;7410.370118539877;7430.851382972311;7515.999999999891;7519.887201691979;6806.319368063154;6837.2820853809635;6837.658117094026;6852.417544508573;6956.443484521327;8417.410781245255;8444.49333039995;8641.222289993932;8658.78353041179;8665.046844846936;8724.171203735636;8795.12048795141;8847.51338676379;8894.4663320201;8904.910950890408;6928.1339832520935;7048.471364647775;7050.612216328152;7066.4346852250665;7070.292970703094;6563.7702680407865;6684.532082754161;6759.831004224934;6808.285130061272;7433.25667433274;6808.251092379934;6815.420689241271;6918.584884906922;7011.439084873095;7051.471139664443;6686.13080299558;6762.763303284361;6781;6791.524593278423;6925.965370173117;6751.088603038527;6781.186257649114;6796.2524122347495;6816;6832.795016149524;6341.55609107358;6380.1067850501895;6444.420002199825;6548;6550.475961922939;6393.200849893763;6405.711742971671;6421.325760795049;6440.67796610177;6451.41937225652 05:03:00 INFO - PROCESS | 26153 | |4;prototype.html;311.94385010698096;315.90364938693705;325.05571315328973;330.68089293831446;331.1060137628403;5701.971490142554;5815.476607105365;5853.85365365865;5859.648421094737;5911.733971971255;5640.971795141028;5732.31212254529;5759.942400575993;5761.9135712964235;5824.475797178253;4315.201687687772;4474.127545128693;4481.574250446219;4486.573775491323;4487.439070116236;4391.143726973241;4400.09797991411;4496.7751612419415;4499.572540608653;4531.184386810372;4469.910601787962;4479.977600111994;4486.057927835159;4490.730556166641;4495.775211239441;4467.441569803775;4476.328550717386;4480.596746292833;4487.685861989678;4497.662675299365;3945.940810887839;3948.6643635291034;3954.8813535593977;3962.980185099056;3969.4641223434755;5470.152126420411;5557.166425036236;5586.692731899744;5603.187537807036;5604.607677462579;5227.294315267428;5235.450277720861;5237.554807841338;5239.292695486099;5240.6069544784295;5855.121731740231;5997.7001149942325;6162.815115546541;6167.982282923313;6176.413240742122;4266.935995960063;4343.761093139876;4369.169857727022;4370.453693288339;4380;6456.289808121103;6458.2895881453005;6466.612003279818;6469.482441404676;6476.352364763533;4457.977710111493;4474.865754027384;4475.395821564112;4476.328550717353;4481.260592002283;4397.1865204937185;4516;4524.547545245515;4531;4536.818527258873;5181.974090129525;5187.377514698261;5187.974060129751;5193.948060519422;5196.506331898464;6219.875602487925;6225.968870155621;6226.439620434183;6230.127782110508;6248.750049997949;1576.1252504859683;1584.7939767830107;1587.1826009605063;1587.8332775058452;1588.221771332039;1024.99487502562;1047.9895201048043;1049.4752623688155;1104.1332553945115;1122.4892673833385;5844.1233814927255;5854.677992710356;5855.092460668603;5859.267591551056;5866.090755932837;6345.746170153235;6348.555601107971;6365.586236894587;6368.968155159195;6372.968135159294;5860.941390586125;5870.706464676835;5870.7945221917025;5873.823785286449;5874.412558744091;3385.0860267727576;3473.513708080871;3479.982600087034;3485.337785820686;3485.6514348565447;6744.123263975652;6750.898736518956;6753.29090445506;6754.3245675433045;6758.83102922421;6580.934190658128;6582.769603063965;6583.967080164569;6585.1439312889015;6590.670466476657;6515.837104072341;6711.832204194934;6731.629760363227;6734;6791.864162716817;6190.41191086846;6284.937150628435;6379.93620063812;6490.091387205701;6493.512986526124;6268.717907694256;6291.779787707408;6303.590266632654;6308.369163083655;6330.240371155307;6741.752775736504;6755.662216889037;6756.121704178426;6759.628220447923;6761.154855643044;4395.164918665443;4436.134953684001;4452.688311818141;4480.551944805494;4496.820127194876;6481.5138864584405;6487.22153341602;6487.610743355413;6494.383033611799;6495;6309.463695585927;6326.746930122744;6328.6202827830475;6332.905006424815;6346.428821405911;6089.2084029077705;6269.279032911274;6304.968475157778;6314.431701146918;6320.778772742932;6131.20294361748;6142.324344322032;6153.353897840662;6167.969160154171;6208;6303.212098487689;6315.210598675166;6339.524535659752;6349.428551430394;6358.745650173942;6075.817725468243;6106.6336019838955;6148.754049837957;6149.96925015372;6167.105769663463 05:03:00 INFO - PROCESS | 26153 | |5;yui.html;708.4084789201017;766.5707203965779;779.3453499060795;794.8608993426151;826.4917075998263;12107.21303115298;12321.19912205706;12326.506939722412;12334;12344.876551234496;11948;12255.387230638476;12306.753864922695;12314.568990085349;12371.505139794397;647.2491909385114;654.3685343643383;770.0797546931419;774.5662429039728;787.2796391301952;772.9149793522736;777.4907435629672;779.2908453307491;779.5751315533048;779.949303295284;813.8168911994784;816.7508909782514;816.8774683797418;817.1297568089994;817.2236375443323;679.1137565477051;687.2543290529757;808.3533173461237;817.7628487738549;819.0785366462729;608.4068033667183;608.4767100293744;608.9451949324582;609.065085094378;609.1776102261955;11805.114616403718;11813.114016448715;11863;11880.287182769061;11881.881181188164;10978.286411383235;11144.27562208462;11161.553537858557;11483.712907177387;11564.306141631529;9743.415395076318;9885.654002109892;9910.819619683658;9959.153471954893;10221.437820919846;3382.932341353184;3444.190615205425;3495.720342372604;3497.667721566447;3528.1179705073732;42501.787491062656;42701.786491067345;42886.57113428857;42981.57018429807;43086.78456607697;8578.270846977952;8865.867011994826;8950.776230594287;9127.224185944136;9415.105564971316;16023.919880400523;16034.118123503085;16173.838261617468;16193;16194.595135121715;6648.5678430901835;6661.16735408074;6727.125473688389;6817.488688348394;6901.965490172517;3883.2815929053218;3908.4528166056207;3919.2161567686576;4003.139325045129;4032.495938007749;3007.9548806767916;3036.878524859025;3043.832589207615;3045.8629361678777;3048.131282584453;543.4266848474817;543.5678635484799;545.6616897523561;550.0539072794786;567.5998421113105;623.1991890420787;643.6138317009757;664.9202095748541;666.7832954289876;706.3113464372196;7320.859057990919;7907.9604601976625;7929.405294602928;8030.718924837603;8169.714060007871;562.7467639562213;621.9067139929047;627.6987046217841;663.6316844151543;670.7048898484651;3536;3820.5427415150525;3839.9232015359535;3850.3261929162845;3881.6894648428056;50834.745826270635;51695;62002.689986549776;63199.524026178085;63212.68393658094;45288;46339.68301584975;46629.30056049162;46776.532234677215;47146.5285347149;42995.35506967398;43125.35311970323;43596.78201608972;44147.558524414344;44379.112417751465;7731.574763387843;7736.7292144774665;7890.210978902064;7938.5236885787035;7968.8534629113965;31515;31842.84078579592;31996.360072798878;32799.67200327966;32935.67064329326;38455;39321;42201;42687.14625707531;42928.78535607302;9471.337006409485;9814.705558833246;9969.950150249493;10064.647737329158;10105.885939006796;10866.67399978002;11124.833127503256;11133.944330278297;11690.181687281809;11955.701107472034;10320.741981450223;10377.584896604052;10918.50866710984;11000.174986875856;11355.659330220107;10668.519916603927;10721.463926803784;11044.668659940215;11421.714457138638;11511.36687482163;10052.296339256181;10078.596856125672;10493.317934334244;10565.9471702641;10893.618723344962;8808.51553164582;8973;9083.500407477652;9218.308626852879;9471.526423678652;8735.868961965449;8821.867671984797;8904.73285801427;9307;9404.717858464257 05:03:00 INFO - PROCESS | 26153 | __end_tp_report 05:03:00 INFO - PROCESS | 26153 | __start_cc_report 05:03:00 INFO - PROCESS | 26153 | _x_x_mozilla_cycle_collect,919 05:03:00 INFO - PROCESS | 26153 | __end_cc_report 05:03:00 INFO - PROCESS | 26153 | __startTimestamp1452603780918__endTimestamp 05:03:00 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | ------- Summary: start ------- 05:03:00 INFO - PROCESS | 26153 | Number of tests: 6 05:03:00 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | [#0] dojo.html Cycles:165 Average:5160.70 Median:5306.00 stddev:561.90 (10.6%) stddev-sans-first:540.14 05:03:00 INFO - PROCESS | 26153 | Values: 3112.0 3165.7 3204.7 3221.8 3232.5 5021.6 5079.2 5141.9 5152.4 5209.3 5112.3 5116.2 5163.7 5173.9 5246.5 4499.8 4648.7 4679.1 4685.2 4810.7 4766.6 4772.9 4799.3 4848.2 4872.2 4613.9 4684.0 4721.5 4781.2 4797.9 4622.3 4633.7 4642.0 4647.5 4659.0 4593.1 4608.9 4641.9 4642.5 4654.9 4956.9 5022.2 5053.5 5089.3 5122.4 5528.9 5533.6 5549.6 5561.9 5569.5 5439.1 5561.5 5564.9 5569.1 5570.1 5132.7 5149.2 5152.3 5271.1 5306.0 5692.3 5703.9 5705.3 5714.7 5764.4 5343.1 5472.0 5474.6 5474.7 5477.7 5521.4 5550.1 5586.4 5668.0 5715.0 5513.9 5522.5 5525.5 5525.8 5529.4 5407.6 5462.1 5509.0 5513.8 5558.0 4314.9 4326.8 4361.2 4421.8 4454.0 3863.5 3866.3 3871.6 3902.3 3911.1 5120.4 5128.1 5173.5 5175.8 5181.8 5461.7 5474.2 5481.3 5615.4 5702.0 5193.6 5201.3 5206.1 5242.1 5260.4 4773.0 4804.0 4822.6 4826.1 4827.5 5649.8 5742.2 5770.9 5771.7 5825.3 5551.1 5559.5 5560.5 5571.1 5606.0 5785.7 5814.1 5827.8 5831.8 5834.2 5423.4 5530.1 5530.5 5568.4 5682.3 5586.8 5615.7 5628.3 5635.9 5636.0 5485.1 5486.5 5496.2 5502.8 5541.3 5332.1 5401.2 5448.1 5459.8 5498.2 5273.4 5279.7 5284.2 5284.4 5287.2 5305.2 5356.5 5399.2 5443.8 5453.1 5280.8 5304.1 5333.7 5353.6 5405.3 05:03:00 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | [#1] ext.html Cycles:180 Average:44407.00 Median:18054.91 stddev:73703.78 (408.2%) stddev-sans-first:73836.93 05:03:00 INFO - PROCESS | 26153 | Values: 534.7 550.3 557.9 564.4 578.2 28116.4 28794.1 28830.0 35234.8 35981.0 37899.0 38385.0 38411.8 38528.4 38528.8 8541.9 8865.6 8871.9 8878.8 8881.9 7495.4 7676.5 10066.6 10276.3 10278.6 10091.4 10159.9 10160.6 10169.2 10178.3 7435.3 7615.0 9948.8 10029.0 10029.2 7369.3 10095.4 10352.9 10369.7 10371.7 34615.8 35019.3 35086.6 35091.8 35198.6 3137.8 3171.6 3189.3 3515.3 3584.2 27322.0 27484.2 27711.4 28108.4 29792.9 3966.6 3973.4 3995.8 4028.9 4106.2 246316.0 247318.0 248405.0 248857.0 250325.0 50727.2 51786.5 56548.4 56937.0 56967.0 61456.0 61970.4 62091.7 62204.4 62228.7 13991.9 14205.9 14207.6 14213.9 14221.0 9717.2 9743.1 9744.4 9747.0 9754.9 19218.2 19360.9 19431.5 19499.5 19532.4 7179.3 7722.4 7784.6 7851.1 7878.4 908.8 970.1 1021.0 1055.3 1089.4 26922.6 27043.9 27456.8 27967.2 28038.0 1026.5 1036.4 1094.7 1165.7 1170.0 13827.2 14163.2 14181.4 14626.9 14668.6 296854.0 302967.0 304193.0 305399.0 305422.0 49291.0 49564.5 49674.8 49685.5 49740.0 1310.7 1317.8 1330.8 1332.5 1351.5 26970.5 27527.0 27949.9 28189.4 28212.6 43456.0 43862.3 43867.1 43867.1 43907.8 290067.0 292234.0 292363.0 293181.0 294094.0 27805.4 28337.3 30983.0 30988.0 31037.7 35208.8 35725.1 35990.3 35993.5 36515.8 30821.4 31125.8 39244.0 39868.2 40436.0 29553.5 35941.5 36435.5 37116.5 38951.4 33799.5 35519.1 35548.6 35632.6 35672.8 32920.5 34222.8 35430.3 35505.0 35585.3 25541.6 25943.5 26064.3 26076.9 26153.2 05:03:00 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | [#2] jquery.html Cycles:180 Average:25616.90 Median:497262.50 stddev:80562.56 (16.2%) stddev-sans-first:80766.25 05:03:00 INFO - PROCESS | 26153 | Values: 368.6 369.2 369.6 370.4 374.6 13047.4 13351.3 15786.8 16008.0 16534.9 9895.9 12938.4 13565.0 13582.9 13587.7 4702.3 4893.9 4900.0 4912.1 4922.4 3742.9 3858.3 4808.7 4861.8 5211.0 5371.9 5411.1 5416.6 5417.2 5421.9 5350.2 6004.5 6014.6 6017.5 6058.9 5193.1 5389.7 5401.9 5407.0 5434.2 19381.7 19841.3 19954.2 20104.2 20127.0 1992.2 2034.4 2038.8 2048.1 2083.3 8502.6 8514.6 8687.3 8865.2 8919.0 1719.3 1763.5 1770.8 1822.1 1830.8 52139.2 54925.5 56020.0 56295.7 56811.1 9661.7 9692.2 9861.5 9891.6 9920.5 12463.1 12530.0 12535.0 12536.4 12551.4 6955.3 6969.6 7050.2 7185.6 7289.3 6659.1 6668.8 6697.6 6799.5 6843.1 4302.1 4313.2 4366.4 4431.8 4436.9 1696.9 1743.7 1753.1 1761.0 1803.0 901.5 906.9 987.1 1019.6 1035.1 15696.8 15936.0 16177.1 16308.1 16494.1 867.3 883.9 886.7 901.7 902.1 8806.9 9843.8 9847.5 9937.5 10068.8 482669.0 486391.0 497206.0 497319.0 501045.0 67144.7 67611.3 68403.0 69187.7 69928.0 1121.0 1132.2 1135.4 1141.4 1148.1 13864.0 14613.6 14714.9 15065.7 15071.5 29523.0 29689.7 29988.6 32000.0 33723.5 40062.2 40994.8 41834.8 42638.1 44159.6 7008.7 7315.2 7315.9 7374.5 7421.9 9294.3 11052.9 11064.3 11295.9 11398.1 5368.8 6597.2 8522.9 8674.7 9337.9 9626.5 9969.9 10171.9 10216.7 10234.4 7677.3 9084.7 9287.1 9576.2 9713.2 6629.8 6813.5 6826.7 6908.7 6922.8 7968.6 8544.8 8721.3 9122.7 9210.0 05:03:00 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | [#3] mootools.html Cycles:180 Average:5434.21 Median:6399.46 stddev:2837.60 (44.3%) stddev-sans-first:2828.03 05:03:00 INFO - PROCESS | 26153 | Values: 1238.7 1444.1 1458.2 1603.1 1757.0 6920.6 7134.4 7409.7 7545.4 7564.3 7632.4 7632.6 7717.0 7725.9 7727.8 1984.8 2032.2 2263.8 2291.8 2293.9 2260.6 2314.6 2316.8 2317.8 2318.0 2211.1 2232.5 2255.8 2258.9 2259.1 2239.3 2250.4 2262.2 2265.2 2269.0 2259.2 2264.0 2271.1 2271.4 2278.5 7809.4 7868.8 7878.4 7893.3 7964.1 4660.9 4786.3 4789.1 4796.2 4800.7 3027.3 3105.8 3119.4 3127.0 3150.4 977.3 980.8 1035.0 1041.4 1083.0 10308.7 10467.8 10478.7 10493.1 10518.0 4177.4 4250.3 4335.8 4353.6 4354.5 9246.3 9252.8 9269.4 9314.0 9320.7 4715.2 4715.5 4723.2 4728.0 4728.6 4203.3 4208.9 4250.9 4252.0 4253.8 3483.7 3557.6 3587.1 3704.6 3707.1 1236.1 1242.6 1256.2 1262.4 1268.3 1332.0 1333.6 1368.7 1375.8 1387.4 7451.6 7455.3 7480.4 7504.1 7508.2 567.4 575.7 583.4 592.2 592.8 5718.1 5819.7 5845.1 5884.0 5886.9 9230.0 9576.8 9594.4 9642.9 9658.5 9355.3 9512.7 9522.5 9546.3 9557.0 7344.4 7410.4 7430.9 7516.0 7519.9 6806.3 6837.3 6837.7 6852.4 6956.4 8417.4 8444.5 8641.2 8658.8 8665.0 8724.2 8795.1 8847.5 8894.5 8904.9 6928.1 7048.5 7050.6 7066.4 7070.3 6563.8 6684.5 6759.8 6808.3 7433.3 6808.3 6815.4 6918.6 7011.4 7051.5 6686.1 6762.8 6781.0 6791.5 6926.0 6751.1 6781.2 6796.3 6816.0 6832.8 6341.6 6380.1 6444.4 6548.0 6550.5 6393.2 6405.7 6421.3 6440.7 6451.4 05:03:00 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | [#4] prototype.html Cycles:180 Average:5211.73 Median:5855.11 stddev:1569.23 (26.8%) stddev-sans-first:1529.93 05:03:00 INFO - PROCESS | 26153 | Values: 311.9 315.9 325.1 330.7 331.1 5702.0 5815.5 5853.9 5859.6 5911.7 5641.0 5732.3 5759.9 5761.9 5824.5 4315.2 4474.1 4481.6 4486.6 4487.4 4391.1 4400.1 4496.8 4499.6 4531.2 4469.9 4480.0 4486.1 4490.7 4495.8 4467.4 4476.3 4480.6 4487.7 4497.7 3945.9 3948.7 3954.9 3963.0 3969.5 5470.2 5557.2 5586.7 5603.2 5604.6 5227.3 5235.5 5237.6 5239.3 5240.6 5855.1 5997.7 6162.8 6168.0 6176.4 4266.9 4343.8 4369.2 4370.5 4380.0 6456.3 6458.3 6466.6 6469.5 6476.4 4458.0 4474.9 4475.4 4476.3 4481.3 4397.2 4516.0 4524.5 4531.0 4536.8 5182.0 5187.4 5188.0 5193.9 5196.5 6219.9 6226.0 6226.4 6230.1 6248.8 1576.1 1584.8 1587.2 1587.8 1588.2 1025.0 1048.0 1049.5 1104.1 1122.5 5844.1 5854.7 5855.1 5859.3 5866.1 6345.7 6348.6 6365.6 6369.0 6373.0 5860.9 5870.7 5870.8 5873.8 5874.4 3385.1 3473.5 3480.0 3485.3 3485.7 6744.1 6750.9 6753.3 6754.3 6758.8 6580.9 6582.8 6584.0 6585.1 6590.7 6515.8 6711.8 6731.6 6734.0 6791.9 6190.4 6284.9 6379.9 6490.1 6493.5 6268.7 6291.8 6303.6 6308.4 6330.2 6741.8 6755.7 6756.1 6759.6 6761.2 4395.2 4436.1 4452.7 4480.6 4496.8 6481.5 6487.2 6487.6 6494.4 6495.0 6309.5 6326.7 6328.6 6332.9 6346.4 6089.2 6269.3 6305.0 6314.4 6320.8 6131.2 6142.3 6153.4 6168.0 6208.0 6303.2 6315.2 6339.5 6349.4 6358.7 6075.8 6106.6 6148.8 6150.0 6167.1 05:03:00 INFO - PROCESS | 26153 | 05:03:00 INFO - PROCESS | 26153 | [#5] yui.html Cycles:180 Average:12828.71 Median:46702.92 stddev:15037.51 (32.2%) stddev-sans-first:15052.15 05:03:00 INFO - PROCESS | 26153 | Values: 708.4 766.6 779.3 794.9 826.5 12107.2 12321.2 12326.5 12334.0 12344.9 11948.0 12255.4 12306.8 12314.6 12371.5 647.2 654.4 770.1 774.6 787.3 772.9 777.5 779.3 779.6 779.9 813.8 816.8 816.9 817.1 817.2 679.1 687.3 808.4 817.8 819.1 608.4 608.5 608.9 609.1 609.2 11805.1 11813.1 11863.0 11880.3 11881.9 10978.3 11144.3 11161.6 11483.7 11564.3 9743.4 9885.7 9910.8 9959.2 10221.4 3382.9 3444.2 3495.7 3497.7 3528.1 42501.8 42701.8 42886.6 42981.6 43086.8 8578.3 8865.9 8950.8 9127.2 9415.1 16023.9 16034.1 16173.8 16193.0 16194.6 6648.6 6661.2 6727.1 6817.5 6902.0 3883.3 3908.5 3919.2 4003.1 4032.5 3008.0 3036.9 3043.8 3045.9 3048.1 543.4 543.6 545.7 550.1 567.6 623.2 643.6 664.9 666.8 706.3 7320.9 7908.0 7929.4 8030.7 8169.7 562.7 621.9 627.7 663.6 670.7 3536.0 3820.5 3839.9 3850.3 3881.7 50834.7 51695.0 62002.7 63199.5 63212.7 45288.0 46339.7 46629.3 46776.5 47146.5 42995.4 43125.4 43596.8 44147.6 44379.1 7731.6 7736.7 7890.2 7938.5 7968.9 31515.0 31842.8 31996.4 32799.7 32935.7 38455.0 39321.0 42201.0 42687.1 42928.8 9471.3 9814.7 9970.0 10064.6 10105.9 10866.7 11124.8 11133.9 11690.2 11955.7 10320.7 10377.6 10918.5 11000.2 11355.7 10668.5 10721.5 11044.7 11421.7 11511.4 10052.3 10078.6 10493.3 10565.9 10893.6 8808.5 8973.0 9083.5 9218.3 9471.5 8735.9 8821.9 8904.7 9307.0 9404.7 05:03:00 INFO - PROCESS | 26153 | -------- Summary: end -------- 05:03:00 INFO - PROCESS | 26153 | 05:03:01 INFO - TEST-INFO | 26153: exit 0 05:03:01 INFO - TEST-OK | dromaeo_css | took 1112091ms 05:03:01 INFO - TEST-START | kraken 05:03:01 INFO - operating with platform_type : linux_ 05:03:01 INFO - Initialising browser for kraken test... 05:03:01 INFO - TEST-INFO | started process 26341 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpROzaIn/profile http://localhost:41029/getInfo.html) 05:03:05 INFO - PROCESS | 26341 | __metrics Screen width/height:1600/1200 05:03:05 INFO - PROCESS | 26341 | colorDepth:24 05:03:05 INFO - PROCESS | 26341 | Browser inner width/height: 1024/697 05:03:05 INFO - PROCESS | 26341 | __metrics 05:03:05 INFO - PROCESS | 26341 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:03:05 INFO - PROCESS | 26341 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:03:06 INFO - TEST-INFO | 26341: exit 0 05:03:06 INFO - Browser initialized. 05:03:06 INFO - Running cycle 1/1 for kraken test... 05:03:06 INFO - TEST-INFO | started process 26422 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpROzaIn/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:18 INFO - PROCESS | 26422 | RSS: Main: 148885504 05:03:18 INFO - PROCESS | 26422 | 05:03:20 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41029/tests/kraken/driver.html?testName=audio-beat-detection) 05:03:21 INFO - PROCESS | 26422 | RSS: Main: 186552320 05:03:21 INFO - PROCESS | 26422 | 05:03:23 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41029/tests/kraken/driver.html?testName=audio-dft) 05:03:24 INFO - PROCESS | 26422 | RSS: Main: 176123904 05:03:24 INFO - PROCESS | 26422 | 05:03:27 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41029/tests/kraken/driver.html?testName=audio-fft) 05:03:27 INFO - PROCESS | 26422 | RSS: Main: 160616448 05:03:27 INFO - PROCESS | 26422 | 05:03:29 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41029/tests/kraken/driver.html?testName=audio-oscillator) 05:03:30 INFO - PROCESS | 26422 | RSS: Main: 163409920 05:03:30 INFO - PROCESS | 26422 | 05:03:32 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41029/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:03:33 INFO - PROCESS | 26422 | RSS: Main: 163700736 05:03:33 INFO - PROCESS | 26422 | 05:03:35 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41029/tests/kraken/driver.html?testName=imaging-darkroom) 05:03:36 INFO - PROCESS | 26422 | RSS: Main: 164933632 05:03:36 INFO - PROCESS | 26422 | 05:03:39 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41029/tests/kraken/driver.html?testName=imaging-desaturate) 05:03:40 INFO - PROCESS | 26422 | RSS: Main: 166887424 05:03:40 INFO - PROCESS | 26422 | 05:03:43 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41029/tests/kraken/driver.html?testName=json-parse-financial) 05:03:43 INFO - PROCESS | 26422 | RSS: Main: 166424576 05:03:43 INFO - PROCESS | 26422 | 05:03:45 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41029/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:03:45 INFO - PROCESS | 26422 | RSS: Main: 176869376 05:03:45 INFO - PROCESS | 26422 | 05:03:47 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41029/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:03:48 INFO - PROCESS | 26422 | RSS: Main: 175677440 05:03:48 INFO - PROCESS | 26422 | 05:03:50 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41029/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:03:51 INFO - PROCESS | 26422 | RSS: Main: 186314752 05:03:51 INFO - PROCESS | 26422 | 05:03:53 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41029/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:03:54 INFO - PROCESS | 26422 | RSS: Main: 190824448 05:03:54 INFO - PROCESS | 26422 | 05:03:56 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41029/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:03:57 INFO - PROCESS | 26422 | RSS: Main: 190689280 05:03:57 INFO - PROCESS | 26422 | 05:03:58 INFO - PROCESS | 26422 | Cycle 1(1): loaded http://localhost:41029/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41029/tests/kraken/driver.html?testName=ai-astar) 05:03:59 INFO - PROCESS | 26422 | RSS: Main: 247910400 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | __start_tp_report 05:03:59 INFO - PROCESS | 26422 | _x_x_mozilla_page_load 05:03:59 INFO - PROCESS | 26422 | _x_x_mozilla_page_load_details 05:03:59 INFO - PROCESS | 26422 | |i|pagename|runs| 05:03:59 INFO - PROCESS | 26422 | |0;ai-astar;111;111;105;111;112;110;100;111;111;110 05:03:59 INFO - PROCESS | 26422 | |1;audio-beat-detection;144;124;150;159;125;153;150;125;162;125 05:03:59 INFO - PROCESS | 26422 | |2;audio-dft;161;161;160;161;160;161;159;163;162;161 05:03:59 INFO - PROCESS | 26422 | |3;audio-fft;71;74;89;100;72;71;72;100;93;71 05:03:59 INFO - PROCESS | 26422 | |4;audio-oscillator;95;95;140;95;96;95;143;95;96;137 05:03:59 INFO - PROCESS | 26422 | |5;imaging-gaussian-blur;109;109;109;109;109;109;110;109;110;109 05:03:59 INFO - PROCESS | 26422 | |6;imaging-darkroom;145;146;145;145;145;146;145;145;146;146 05:03:59 INFO - PROCESS | 26422 | |7;imaging-desaturate;96;95;95;95;96;96;95;96;95;95 05:03:59 INFO - PROCESS | 26422 | |8;json-parse-financial;57;56;56;56;56;56;57;69;53;53 05:03:59 INFO - PROCESS | 26422 | |9;json-stringify-tinderbox;67;68;68;67;68;67;68;68;69;68 05:03:59 INFO - PROCESS | 26422 | |10;stanford-crypto-aes;91;91;86;84;86;86;85;93;88;85 05:03:59 INFO - PROCESS | 26422 | |11;stanford-crypto-ccm;132;119;125;126;121;116;132;119;119;124 05:03:59 INFO - PROCESS | 26422 | |12;stanford-crypto-pbkdf2;149;151;150;152;150;140;150;153;155;140 05:03:59 INFO - PROCESS | 26422 | |13;stanford-crypto-sha256-iterative;71;68;68;68;67;66;68;66;67;68 05:03:59 INFO - PROCESS | 26422 | __end_tp_report 05:03:59 INFO - PROCESS | 26422 | __start_cc_report 05:03:59 INFO - PROCESS | 26422 | _x_x_mozilla_cycle_collect,964 05:03:59 INFO - PROCESS | 26422 | __end_cc_report 05:03:59 INFO - PROCESS | 26422 | __startTimestamp1452603839159__endTimestamp 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | ------- Summary: start ------- 05:03:59 INFO - PROCESS | 26422 | Number of tests: 14 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#0] ai-astar Cycles:10 Average:109.20 Median:111.00 stddev:3.77 (3.4%) stddev-sans-first:3.94 05:03:59 INFO - PROCESS | 26422 | Values: 111.0 111.0 105.0 111.0 112.0 110.0 100.0 111.0 111.0 110.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:150.00 stddev:15.39 (10.3%) stddev-sans-first:16.30 05:03:59 INFO - PROCESS | 26422 | Values: 144.0 124.0 150.0 159.0 125.0 153.0 150.0 125.0 162.0 125.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 05:03:59 INFO - PROCESS | 26422 | Values: 161.0 161.0 160.0 161.0 160.0 161.0 159.0 163.0 162.0 161.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#3] audio-fft Cycles:10 Average:81.30 Median:72.00 stddev:12.65 (17.6%) stddev-sans-first:12.86 05:03:59 INFO - PROCESS | 26422 | Values: 71.0 74.0 89.0 100.0 72.0 71.0 72.0 100.0 93.0 71.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#4] audio-oscillator Cycles:10 Average:108.70 Median:95.00 stddev:21.65 (22.8%) stddev-sans-first:22.39 05:03:59 INFO - PROCESS | 26422 | Values: 95.0 95.0 140.0 95.0 96.0 95.0 143.0 95.0 96.0 137.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:03:59 INFO - PROCESS | 26422 | Values: 109.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 110.0 109.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 05:03:59 INFO - PROCESS | 26422 | Values: 145.0 146.0 145.0 145.0 145.0 146.0 145.0 145.0 146.0 146.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 05:03:59 INFO - PROCESS | 26422 | Values: 96.0 95.0 95.0 95.0 96.0 96.0 95.0 96.0 95.0 95.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:56.00 stddev:4.48 (8.0%) stddev-sans-first:4.76 05:03:59 INFO - PROCESS | 26422 | Values: 57.0 56.0 56.0 56.0 56.0 56.0 57.0 69.0 53.0 53.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 05:03:59 INFO - PROCESS | 26422 | Values: 67.0 68.0 68.0 67.0 68.0 67.0 68.0 68.0 69.0 68.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:87.00 stddev:3.10 (3.6%) stddev-sans-first:3.02 05:03:59 INFO - PROCESS | 26422 | Values: 91.0 91.0 86.0 84.0 86.0 86.0 85.0 93.0 88.0 85.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#11] stanford-crypto-ccm Cycles:10 Average:123.30 Median:124.50 stddev:5.54 (4.4%) stddev-sans-first:4.90 05:03:59 INFO - PROCESS | 26422 | Values: 132.0 119.0 125.0 126.0 121.0 116.0 132.0 119.0 119.0 124.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.00 Median:150.50 stddev:5.06 (3.4%) stddev-sans-first:5.36 05:03:59 INFO - PROCESS | 26422 | Values: 149.0 151.0 150.0 152.0 150.0 140.0 150.0 153.0 155.0 140.0 05:03:59 INFO - PROCESS | 26422 | 05:03:59 INFO - PROCESS | 26422 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.42 (2.1%) stddev-sans-first:0.87 05:03:59 INFO - PROCESS | 26422 | Values: 71.0 68.0 68.0 68.0 67.0 66.0 68.0 66.0 67.0 68.0 05:03:59 INFO - PROCESS | 26422 | -------- Summary: end -------- 05:03:59 INFO - PROCESS | 26422 | 05:04:00 INFO - TEST-INFO | 26422: exit 0 05:04:00 INFO - TEST-OK | kraken | took 58516ms 05:04:00 INFO - TEST-START | v8_7 05:04:00 INFO - operating with platform_type : linux_ 05:04:00 INFO - Initialising browser for v8_7 test... 05:04:00 INFO - TEST-INFO | started process 26671 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDoGB35/profile http://localhost:41029/getInfo.html) 05:04:03 INFO - PROCESS | 26671 | __metrics Screen width/height:1600/1200 05:04:03 INFO - PROCESS | 26671 | colorDepth:24 05:04:03 INFO - PROCESS | 26671 | Browser inner width/height: 1024/697 05:04:03 INFO - PROCESS | 26671 | __metrics 05:04:03 INFO - PROCESS | 26671 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 05:04:03 INFO - PROCESS | 26671 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:04:04 INFO - TEST-INFO | 26671: exit 0 05:04:04 INFO - Browser initialized. 05:04:04 INFO - Running cycle 1/1 for v8_7 test... 05:04:04 INFO - TEST-INFO | started process 26752 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDoGB35/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:16 INFO - PROCESS | 26752 | RSS: Main: 148475904 05:04:16 INFO - PROCESS | 26752 | 05:04:38 INFO - PROCESS | 26752 | Cycle 1(1): loaded http://localhost:41029/tests/v8_7/run.html (next: http://localhost:41029/tests/v8_7/run.html) 05:04:39 INFO - PROCESS | 26752 | RSS: Main: 581431296 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | __start_tp_report 05:04:39 INFO - PROCESS | 26752 | _x_x_mozilla_page_load 05:04:39 INFO - PROCESS | 26752 | _x_x_mozilla_page_load_details 05:04:39 INFO - PROCESS | 26752 | |i|pagename|runs| 05:04:39 INFO - PROCESS | 26752 | |0;Richards;166.16816218012627 05:04:39 INFO - PROCESS | 26752 | |1;DeltaBlue;190.29495718363464 05:04:39 INFO - PROCESS | 26752 | |2;Encrypt;284.8191398461977 05:04:39 INFO - PROCESS | 26752 | |3;Decrypt;5246.073298429319 05:04:39 INFO - PROCESS | 26752 | |4;RayTrace;1145.475372279496 05:04:39 INFO - PROCESS | 26752 | |5;Earley;783.6990595611285 05:04:39 INFO - PROCESS | 26752 | |6;Boyer;12358.024691358025 05:04:39 INFO - PROCESS | 26752 | |7;RegExp;43085.10638297872 05:04:39 INFO - PROCESS | 26752 | |8;Splay;625.3908692933084 05:04:39 INFO - PROCESS | 26752 | |9;NavierStokes;4840.579710144928 05:04:39 INFO - PROCESS | 26752 | __end_tp_report 05:04:39 INFO - PROCESS | 26752 | __startTimestamp1452603879060__endTimestamp 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | ------- Summary: start ------- 05:04:39 INFO - PROCESS | 26752 | Number of tests: 10 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#0] Richards Cycles:1 Average:166.17 Median:166.17 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 166.2 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#1] DeltaBlue Cycles:1 Average:190.29 Median:190.29 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 190.3 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#2] Encrypt Cycles:1 Average:284.82 Median:284.82 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 284.8 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#3] Decrypt Cycles:1 Average:5246.07 Median:5246.07 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 5246.1 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#4] RayTrace Cycles:1 Average:1145.48 Median:1145.48 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 1145.5 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#5] Earley Cycles:1 Average:783.70 Median:783.70 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 783.7 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#6] Boyer Cycles:1 Average:12358.02 Median:12358.02 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 12358.0 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#7] RegExp Cycles:1 Average:43085.11 Median:43085.11 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 43085.1 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#8] Splay Cycles:1 Average:625.39 Median:625.39 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 625.4 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - PROCESS | 26752 | [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 05:04:39 INFO - PROCESS | 26752 | Values: 4840.6 05:04:39 INFO - PROCESS | 26752 | -------- Summary: end -------- 05:04:39 INFO - PROCESS | 26752 | 05:04:39 INFO - TEST-INFO | 26752: exit 0 05:04:39 INFO - TEST-OK | v8_7 | took 39840ms 05:04:39 INFO - SUITE-END | took 1210s 05:04:40 INFO - Completed test suite (00:20:10) 05:04:40 INFO - Working with test: dromaeo_css 05:04:40 INFO - Generating results file: dromaeo_css 05:04:40 INFO - Working with test: kraken 05:04:40 INFO - Generating results file: kraken 05:04:40 INFO - javascript benchmark 05:04:40 INFO - Working with test: v8_7 05:04:40 INFO - Generating results file: v8_7 05:04:40 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:04:40 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,201,35]] 05:04:40 INFO - process_Request line: dromaeo_css 6670.78 graph.html#tests=[[72,201,35]] 05:04:40 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:04:40 INFO - process_Request line: kraken 1504.00 graph.html#tests=[[232,201,35]] 05:04:40 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:04:40 INFO - process_Request line: v8_7 19800.59 graph.html#tests=[[230,201,35]] 05:04:40 INFO - RETURN: dromaeo_css: 6670.78 05:04:40 INFO - RETURN: kraken: 1504.00 05:04:40 INFO - RETURN: v8_7: 19800.59 05:04:40 INFO - javascript benchmark 05:04:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3111.9688803111967, 3165.6834316568334, 3204.7275981541566, 3221.7583681223914, 3232.4989626607885, 5021.623378246637, 5079.187330027196, 5141.922871156931, 5152.40747314059, 5209.296744939432, 5112.335396398463, 5116.232565115234, 5163.664361816489, 5173.9482605173935, 5246.501582349681, 4499.7750112494405, 4648.721076735406, 4679.134360143385, 4685.2035154023815, 4810.711357318554, 4766.571008609225, 4772.856814295576, 4799.304100905367, 4848.224284114543, 4872.171730805754, 4613.930791038137, 4684.039771846763, 4721.48063712993, 4781.187198176283, 4797.928031079537, 4622.2835460503675, 4633.745144017078, 4642.0, 4647.512011238805, 4658.97502549442, 4593.058423023305, 4608.861734147981, 4641.930371044437, 4642.512536183686, 4654.86035418938, 4956.925646115311, 5022.246663000543, 5053.469385714484, 5089.312942752718, 5122.436531981479, 5528.944710552923, 5533.6403133796175, 5549.639273447254, 5561.888762224773, 5569.526590239834, 5439.129739241703, 5561.499465048125, 5564.916526252109, 5569.081101618269, 5570.108782594765, 5132.717700526469, 5149.150390185615, 5152.330197074356, 5271.103912334912, 5306.0, 5692.288463942007, 5703.885922281531, 5705.343885453226, 5714.7428365723645, 5764.423557644286, 5343.064963631349, 5471.972640136773, 5474.589405794582, 5474.671519708831, 5477.67133971963, 5521.3926468088475, 5550.139728342114, 5586.385497595261, 5667.979763642584, 5714.999875021855, 5513.917291240635, 5522.530584900329, 5525.530329921921, 5525.778968841281, 5529.364123125892, 5407.567394608422, 5462.0987537056735, 5509.0, 5513.807016754395, 5558.0, 4314.870553883388, 4326.783660816946, 4361.214981303332, 4421.801018954155, 4454.020115574568, 3863.459115723801, 3866.2654095721723, 3871.5934826842777, 3902.2585708715255, 3911.1395492992065, 5120.411152717411, 5128.12821820284, 5173.486503840056, 5175.844724658266, 5181.766820493087, 5461.672299662033, 5474.151506516497, 5481.260029895992, 5615.438456154352, 5701.971490142606, 5193.558547523503, 5201.323827902348, 5206.140986737221, 5242.1088414969545, 5260.395054568697, 4772.976135119372, 4803.95196048042, 4822.614190864722, 4826.058918510927, 4827.493113223061, 5649.830505084854, 5742.224799652077, 5770.855728606734, 5771.711414429262, 5825.271841019958, 5551.139573366135, 5559.52744016747, 5560.527355174693, 5571.1086226203615, 5606.0, 5785.652860828363, 5814.069748840166, 5827.79602713903, 5831.825045248649, 5834.18321434991, 5423.403425623257, 5530.05988981866, 5530.5022547970875, 5568.415316391721, 5682.318121825408, 5586.832395028156, 5615.691136987505, 5628.296462942132, 5635.9436405635415, 5636.0, 5485.122380419114, 5486.533644640091, 5496.230527726201, 5502.80740174092, 5541.2796336476, 5332.120200166929, 5401.216823560483, 5448.128299472065, 5459.754311055933, 5498.1752737089755, 5273.446288139843, 5279.736013199248, 5284.154535274338, 5284.41871394154, 5287.180487024518, 5305.204219367126, 5356.517913387814, 5399.190121481652, 5443.755031023693, 5453.10023846061, 5280.788768449373, 5304.124819404755, 5333.706646134345, 5353.625246232727, 5405.324334458192], "unit": "score", "name": "dojo.html", "value": 5125.583646652219}, {"lowerIsBetter": false, "replicates": [534.6952237224783, 550.257152843661, 557.9163125531172, 564.3594520219551, 578.2425023219582, 28116.437671246564, 28794.13617591473, 28829.99095031677, 35234.8238258809, 35981.0, 37899.0, 38385.0, 38411.80794096032, 38528.42207366892, 38528.80735596318, 8541.87187192191, 8865.556722163898, 8871.911280887172, 8878.822423551524, 8881.911180888173, 7495.437842161832, 7676.501027433227, 10066.647667331645, 10276.280660353777, 10278.640247591336, 10091.444970526618, 10159.898401016006, 10160.644377446792, 10169.237307201953, 10178.338408003494, 7435.256474352575, 7615.048118985127, 9948.751281217956, 10029.0, 10029.197664186884, 7369.336759691653, 10095.39427634337, 10352.896471035268, 10369.688909332732, 10371.740706482324, 34615.826920865235, 35019.29961400783, 35086.649133508596, 35091.82454087713, 35198.64801351979, 3137.8274194919272, 3171.5718378019133, 3189.3142974260413, 3515.2775140181384, 3584.193556449804, 27322.0, 27484.175474735788, 27711.445771084665, 28108.43783124326, 29792.851035744898, 3966.5636779954184, 3973.384125460558, 3995.840166393341, 4028.9194216115516, 4106.2198182345255, 246316.0, 247318.0, 248405.0, 248857.0, 250324.99999999817, 50727.239091413656, 51786.48213517892, 56548.43451565432, 56937.0, 56967.0, 61456.0, 61970.38029619646, 62091.689541552005, 62204.377956220764, 62228.688856555425, 13991.930040349733, 14205.857941420454, 14207.573772786833, 14213.928930355281, 14221.0, 9717.222622190207, 9743.12311891919, 9744.415335079777, 9746.95126524377, 9754.902450975542, 19218.231270749293, 19360.903195483934, 19431.514212144808, 19499.512512187026, 19532.414027579194, 7179.317964793337, 7722.382209423233, 7784.610769461504, 7851.136374998746, 7878.369730421553, 908.7637214324191, 970.1462712812821, 1021.0147207944369, 1055.2507719519074, 1089.4334945828284, 26922.576968072575, 27043.864780676366, 27456.764445599994, 27967.160985170478, 28038.0, 1026.4970164619283, 1036.3781730961364, 1094.6715985204407, 1165.650304908524, 1169.9590514331958, 13827.239501827496, 14163.221022843842, 14181.432742690384, 14626.926865365818, 14668.55994320172, 296854.0, 302967.0, 304193.0, 305399.0, 305422.0, 49291.0, 49564.50435495599, 49674.75162624164, 49685.50314496881, 49740.0, 1310.7050913544567, 1317.8484474285387, 1330.8336457942758, 1332.460353556811, 1351.540476238064, 26970.460590788465, 27527.0, 27949.860250698617, 28189.43621127525, 28212.576811348255, 43456.0, 43862.34206486969, 43867.12265754603, 43867.12265754731, 43907.78046109749, 290067.0, 292234.0, 292363.0, 293181.0, 294094.0, 27805.44389112166, 28337.29156771097, 30982.97221361299, 30988.0, 31037.68962310348, 35208.823955880056, 35725.1068723284, 35990.28019439649, 35993.460098098025, 36515.817420912725, 30821.38357232798, 31125.844370778002, 39244.0, 39868.2026359477, 40435.98910027273, 29553.52751516456, 35941.46087808633, 36435.45346819853, 37116.50817405662, 38951.41572876466, 33799.4930076054, 35519.11202219862, 35548.64451355454, 35632.64367356293, 35672.821635892695, 32920.506192407614, 34222.82888585541, 35430.29139417249, 35505.0, 35585.28829423449, 25541.61687574725, 25943.48113037691, 26064.34839128961, 26076.8696156518, 26153.215403537924], "unit": "score", "name": "ext.html", "value": 17979.29900998898}, {"lowerIsBetter": false, "replicates": [368.56693385272314, 369.1822612912397, 369.61744594344856, 370.42768922015483, 374.6403452685422, 13047.41286642101, 13351.265680387574, 15786.763198552017, 16008.0, 16534.917325413386, 9895.851562226555, 12938.417771200295, 13565.0, 13582.932085339584, 13587.660308492292, 4702.341672165891, 4893.926591101136, 4899.975500122512, 4912.0667073256145, 4922.409310882681, 3742.8877133686033, 3858.344081506151, 4808.735519546423, 4861.829835955742, 5211.0, 5371.946280537203, 5411.107167317388, 5416.645838540365, 5417.241586177938, 5421.891562168754, 5350.170723537858, 6004.519638428916, 6014.609050411709, 6017.548683848685, 6058.939410605925, 5193.065248255312, 5389.703566303851, 5401.891962160774, 5406.972965135149, 5434.239206511092, 19381.709274360754, 19841.30555430553, 19954.201831926704, 20104.195832166697, 20127.0, 1992.2230330171244, 2034.4303594993278, 2038.7961203879643, 2048.068129001301, 2083.260442542891, 8502.574871256413, 8514.616842242114, 8687.3050155988, 8865.157810008039, 8918.955405222998, 1719.2521253254874, 1763.5414792154004, 1770.8317709817675, 1822.1435925115306, 1830.8352248297583, 52139.21791173136, 54925.45074549283, 56020.0, 56295.71852140754, 56811.14783278254, 9661.661841835503, 9692.22462203022, 9861.506924653739, 9891.60433582663, 9920.503974801375, 12463.065270104596, 12530.0, 12535.0, 12536.373181340896, 12551.435185416678, 6955.339242771928, 6969.616671083139, 7050.224475307712, 7185.568865868065, 7289.307515785992, 6659.101021362151, 6668.833279167962, 6697.598144111272, 6799.4900382471515, 6843.076184715099, 4302.139572085595, 4313.158934007901, 4366.366876802909, 4431.756253406029, 4436.911261774811, 1696.9321227150776, 1743.668702946436, 1753.0708724376102, 1760.9823901761074, 1802.97295540567, 901.4861528928448, 906.9365144439957, 987.0770829274651, 1019.6023550815188, 1035.0736091198485, 15696.76454853178, 15936.0, 16177.11025893587, 16308.103054332125, 16494.092824894506, 867.2888231649987, 883.9116088391238, 886.7339798060556, 901.6889173235223, 902.1249388093539, 8806.86789698155, 9843.803123937625, 9847.45838978863, 9937.45344006072, 10068.798624027479, 482669.0, 486391.0, 497206.0, 497319.0, 501045.0, 67144.6642766783, 67611.3238867605, 68403.0, 69187.65406172936, 69928.0, 1121.0471099565468, 1132.1565433751907, 1135.4436326200102, 1141.372245265101, 1148.1274231584057, 13864.0, 14613.634659133606, 14714.926425367805, 15065.698686026437, 15071.4724984625, 29523.0, 29689.663965122054, 29988.550171747876, 32000.0, 33723.494147588295, 40062.1987560253, 40994.79502602468, 41834.790826045675, 42638.1472370557, 44159.55840441555, 7008.719651214098, 7315.231900650568, 7315.890261646186, 7374.483786134919, 7421.925780742124, 9294.349395542247, 11052.889471105185, 11064.28082174656, 11295.943520282675, 11398.145139114766, 5368.812091576776, 6597.175353080865, 8522.914770852212, 8674.739757807276, 9337.859932100888, 9626.518674066408, 9969.850452243078, 10171.898281017095, 10216.744581385525, 10234.437105958947, 7677.270659287359, 9084.682036128705, 9287.117723816227, 9576.186024187744, 9713.174380177481, 6629.801105966829, 6813.488988325995, 6826.692798824164, 6908.689108990007, 6922.757703480354, 7968.601569921597, 8544.829103417773, 8721.258693011165, 9122.726318210463, 9209.953950230207], "unit": "score", "name": "jquery.html", "value": 7804.787472621827}, {"lowerIsBetter": false, "replicates": [1238.6779437346288, 1444.0830072903707, 1458.1688437590574, 1603.0942517477624, 1756.9560760980983, 6920.619365934872, 7134.393576545994, 7409.70361185552, 7545.358644515223, 7564.281393267631, 7632.3894088472925, 7632.6183690815515, 7717.035370578677, 7725.884111738328, 7727.768166955, 1984.8213660770527, 2032.2175962254564, 2263.7849404306608, 2291.793738563529, 2293.8738369389675, 2260.604394231011, 2314.594945884472, 2316.8262380321457, 2317.8261630377706, 2318.026428899866, 2211.060299372768, 2232.520008198237, 2255.76314486979, 2258.9209377671787, 2259.141526220042, 2239.261043855524, 2250.437390652337, 2262.162999690109, 2265.184533567914, 2269.035659844568, 2259.2205689037255, 2263.9660405093937, 2271.057511132878, 2271.3867255840996, 2278.487340348425, 7809.414293927921, 7868.803279917991, 7878.369730421553, 7893.289603935674, 7964.084130325029, 4660.86017419478, 4786.305985632099, 4789.137955168068, 4796.2326027835725, 4800.735959522225, 3027.2734543709353, 3105.844707764603, 3119.3605310911435, 3126.98436507816, 3150.401423729484, 977.3451787302524, 980.7940332530177, 1034.9741256468574, 1041.3907863899628, 1082.9566817327297, 10308.742281442948, 10467.79064418715, 10478.685639430829, 10493.05562499371, 10518.0, 4177.436046133794, 4250.256205164084, 4335.783210839445, 4353.586409291112, 4354.4992325882295, 9246.26029917605, 9252.814943701089, 9269.397489163182, 9314.0, 9320.72037838866, 4715.221988371949, 4715.481297057321, 4723.244280915037, 4728.030753695484, 4728.621710263171, 4203.306454434984, 4208.873733787991, 4250.872473825791, 4252.043290259667, 4253.766042867672, 3483.703885169789, 3557.5908770491205, 3587.0673624857204, 3704.592494825567, 3707.073231692077, 1236.140882086959, 1242.6023672424917, 1256.1772039314264, 1262.3688155922039, 1268.2960956669137, 1331.9800202996962, 1333.6199183232732, 1368.6578355411148, 1375.7661197596233, 1387.3964825301027, 7451.5901625411125, 7455.254474552501, 7480.3641690456925, 7504.061992250969, 7508.2491750825575, 567.4127278266974, 575.689127870945, 583.4165834165834, 592.2094004503949, 592.8369698332993, 5718.056520674126, 5819.709014549256, 5845.123231515222, 5884.029135192731, 5886.911696324559, 9229.953850230706, 9576.760580985392, 9594.376365536218, 9642.855357169648, 9658.517074146264, 9355.345125841126, 9512.667056652985, 9522.476263805556, 9546.331756776959, 9557.0, 7344.449166312548, 7410.370118539877, 7430.851382972311, 7515.999999999891, 7519.887201691979, 6806.319368063154, 6837.2820853809635, 6837.658117094026, 6852.417544508573, 6956.443484521327, 8417.410781245255, 8444.49333039995, 8641.222289993932, 8658.78353041179, 8665.046844846936, 8724.171203735636, 8795.12048795141, 8847.51338676379, 8894.4663320201, 8904.910950890408, 6928.1339832520935, 7048.471364647775, 7050.612216328152, 7066.4346852250665, 7070.292970703094, 6563.7702680407865, 6684.532082754161, 6759.831004224934, 6808.285130061272, 7433.25667433274, 6808.251092379934, 6815.420689241271, 6918.584884906922, 7011.439084873095, 7051.471139664443, 6686.13080299558, 6762.763303284361, 6781.0, 6791.524593278423, 6925.965370173117, 6751.088603038527, 6781.186257649114, 6796.2524122347495, 6816.0, 6832.795016149524, 6341.55609107358, 6380.1067850501895, 6444.420002199825, 6548.0, 6550.475961922939, 6393.200849893763, 6405.711742971671, 6421.325760795049, 6440.67796610177, 6451.41937225652], "unit": "score", "name": "mootools.html", "value": 4417.814467345469}, {"lowerIsBetter": false, "replicates": [311.94385010698096, 315.90364938693705, 325.05571315328973, 330.68089293831446, 331.1060137628403, 5701.971490142554, 5815.476607105365, 5853.85365365865, 5859.648421094737, 5911.733971971255, 5640.971795141028, 5732.31212254529, 5759.942400575993, 5761.9135712964235, 5824.475797178253, 4315.201687687772, 4474.127545128693, 4481.574250446219, 4486.573775491323, 4487.439070116236, 4391.143726973241, 4400.09797991411, 4496.7751612419415, 4499.572540608653, 4531.184386810372, 4469.910601787962, 4479.977600111994, 4486.057927835159, 4490.730556166641, 4495.775211239441, 4467.441569803775, 4476.328550717386, 4480.596746292833, 4487.685861989678, 4497.662675299365, 3945.940810887839, 3948.6643635291034, 3954.8813535593977, 3962.980185099056, 3969.4641223434755, 5470.152126420411, 5557.166425036236, 5586.692731899744, 5603.187537807036, 5604.607677462579, 5227.294315267428, 5235.450277720861, 5237.554807841338, 5239.292695486099, 5240.6069544784295, 5855.121731740231, 5997.7001149942325, 6162.815115546541, 6167.982282923313, 6176.413240742122, 4266.935995960063, 4343.761093139876, 4369.169857727022, 4370.453693288339, 4380.0, 6456.289808121103, 6458.2895881453005, 6466.612003279818, 6469.482441404676, 6476.352364763533, 4457.977710111493, 4474.865754027384, 4475.395821564112, 4476.328550717353, 4481.260592002283, 4397.1865204937185, 4516.0, 4524.547545245515, 4531.0, 4536.818527258873, 5181.974090129525, 5187.377514698261, 5187.974060129751, 5193.948060519422, 5196.506331898464, 6219.875602487925, 6225.968870155621, 6226.439620434183, 6230.127782110508, 6248.750049997949, 1576.1252504859683, 1584.7939767830107, 1587.1826009605063, 1587.8332775058452, 1588.221771332039, 1024.99487502562, 1047.9895201048043, 1049.4752623688155, 1104.1332553945115, 1122.4892673833385, 5844.1233814927255, 5854.677992710356, 5855.092460668603, 5859.267591551056, 5866.090755932837, 6345.746170153235, 6348.555601107971, 6365.586236894587, 6368.968155159195, 6372.968135159294, 5860.941390586125, 5870.706464676835, 5870.7945221917025, 5873.823785286449, 5874.412558744091, 3385.0860267727576, 3473.513708080871, 3479.982600087034, 3485.337785820686, 3485.6514348565447, 6744.123263975652, 6750.898736518956, 6753.29090445506, 6754.3245675433045, 6758.83102922421, 6580.934190658128, 6582.769603063965, 6583.967080164569, 6585.1439312889015, 6590.670466476657, 6515.837104072341, 6711.832204194934, 6731.629760363227, 6734.0, 6791.864162716817, 6190.41191086846, 6284.937150628435, 6379.93620063812, 6490.091387205701, 6493.512986526124, 6268.717907694256, 6291.779787707408, 6303.590266632654, 6308.369163083655, 6330.240371155307, 6741.752775736504, 6755.662216889037, 6756.121704178426, 6759.628220447923, 6761.154855643044, 4395.164918665443, 4436.134953684001, 4452.688311818141, 4480.551944805494, 4496.820127194876, 6481.5138864584405, 6487.22153341602, 6487.610743355413, 6494.383033611799, 6495.0, 6309.463695585927, 6326.746930122744, 6328.6202827830475, 6332.905006424815, 6346.428821405911, 6089.2084029077705, 6269.279032911274, 6304.968475157778, 6314.431701146918, 6320.778772742932, 6131.20294361748, 6142.324344322032, 6153.353897840662, 6167.969160154171, 6208.0, 6303.212098487689, 6315.210598675166, 6339.524535659752, 6349.428551430394, 6358.745650173942, 6075.817725468243, 6106.6336019838955, 6148.754049837957, 6149.96925015372, 6167.105769663463], "unit": "score", "name": "prototype.html", "value": 4700.20094590257}, {"lowerIsBetter": false, "replicates": [708.4084789201017, 766.5707203965779, 779.3453499060795, 794.8608993426151, 826.4917075998263, 12107.21303115298, 12321.19912205706, 12326.506939722412, 12334.0, 12344.876551234496, 11948.0, 12255.387230638476, 12306.753864922695, 12314.568990085349, 12371.505139794397, 647.2491909385114, 654.3685343643383, 770.0797546931419, 774.5662429039728, 787.2796391301952, 772.9149793522736, 777.4907435629672, 779.2908453307491, 779.5751315533048, 779.949303295284, 813.8168911994784, 816.7508909782514, 816.8774683797418, 817.1297568089994, 817.2236375443323, 679.1137565477051, 687.2543290529757, 808.3533173461237, 817.7628487738549, 819.0785366462729, 608.4068033667183, 608.4767100293744, 608.9451949324582, 609.065085094378, 609.1776102261955, 11805.114616403718, 11813.114016448715, 11863.0, 11880.287182769061, 11881.881181188164, 10978.286411383235, 11144.27562208462, 11161.553537858557, 11483.712907177387, 11564.306141631529, 9743.415395076318, 9885.654002109892, 9910.819619683658, 9959.153471954893, 10221.437820919846, 3382.932341353184, 3444.190615205425, 3495.720342372604, 3497.667721566447, 3528.1179705073732, 42501.787491062656, 42701.786491067345, 42886.57113428857, 42981.57018429807, 43086.78456607697, 8578.270846977952, 8865.867011994826, 8950.776230594287, 9127.224185944136, 9415.105564971316, 16023.919880400523, 16034.118123503085, 16173.838261617468, 16193.0, 16194.595135121715, 6648.5678430901835, 6661.16735408074, 6727.125473688389, 6817.488688348394, 6901.965490172517, 3883.2815929053218, 3908.4528166056207, 3919.2161567686576, 4003.139325045129, 4032.495938007749, 3007.9548806767916, 3036.878524859025, 3043.832589207615, 3045.8629361678777, 3048.131282584453, 543.4266848474817, 543.5678635484799, 545.6616897523561, 550.0539072794786, 567.5998421113105, 623.1991890420787, 643.6138317009757, 664.9202095748541, 666.7832954289876, 706.3113464372196, 7320.859057990919, 7907.9604601976625, 7929.405294602928, 8030.718924837603, 8169.714060007871, 562.7467639562213, 621.9067139929047, 627.6987046217841, 663.6316844151543, 670.7048898484651, 3536.0, 3820.5427415150525, 3839.9232015359535, 3850.3261929162845, 3881.6894648428056, 50834.745826270635, 51695.0, 62002.689986549776, 63199.524026178085, 63212.68393658094, 45288.0, 46339.68301584975, 46629.30056049162, 46776.532234677215, 47146.5285347149, 42995.35506967398, 43125.35311970323, 43596.78201608972, 44147.558524414344, 44379.112417751465, 7731.574763387843, 7736.7292144774665, 7890.210978902064, 7938.5236885787035, 7968.8534629113965, 31515.0, 31842.84078579592, 31996.360072798878, 32799.67200327966, 32935.67064329326, 38455.0, 39321.0, 42201.0, 42687.14625707531, 42928.78535607302, 9471.337006409485, 9814.705558833246, 9969.950150249493, 10064.647737329158, 10105.885939006796, 10866.67399978002, 11124.833127503256, 11133.944330278297, 11690.181687281809, 11955.701107472034, 10320.741981450223, 10377.584896604052, 10918.50866710984, 11000.174986875856, 11355.659330220107, 10668.519916603927, 10721.463926803784, 11044.668659940215, 11421.714457138638, 11511.36687482163, 10052.296339256181, 10078.596856125672, 10493.317934334244, 10565.9471702641, 10893.618723344962, 8808.51553164582, 8973.0, 9083.500407477652, 9218.308626852879, 9471.526423678652, 8735.868961965449, 8821.867671984797, 8904.73285801427, 9307.0, 9404.717858464257], "unit": "score", "name": "yui.html", "value": 5899.556822618515}], "name": "dromaeo_css", "value": 6670.777328126795}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 111.0, 105.0, 111.0, 112.0, 110.0, 100.0, 111.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 109.2}, {"lowerIsBetter": true, "replicates": [144.0, 124.0, 150.0, 159.0, 125.0, 153.0, 150.0, 125.0, 162.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 141.7}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 161.0, 160.0, 161.0, 159.0, 163.0, 162.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.9}, {"lowerIsBetter": true, "replicates": [71.0, 74.0, 89.0, 100.0, 72.0, 71.0, 72.0, 100.0, 93.0, 71.0], "unit": "score", "name": "audio-fft", "value": 81.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 140.0, 95.0, 96.0, 95.0, 143.0, 95.0, 96.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 108.7}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 145.4}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [57.0, 56.0, 56.0, 56.0, 56.0, 56.0, 57.0, 69.0, 53.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 56.9}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 68.0, 68.0, 69.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [91.0, 91.0, 86.0, 84.0, 86.0, 86.0, 85.0, 93.0, 88.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 87.5}, {"lowerIsBetter": true, "replicates": [132.0, 119.0, 125.0, 126.0, 121.0, 116.0, 132.0, 119.0, 119.0, 124.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.3}, {"lowerIsBetter": true, "replicates": [149.0, 151.0, 150.0, 152.0, 150.0, 140.0, 150.0, 153.0, 155.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.0}, {"lowerIsBetter": true, "replicates": [71.0, 68.0, 68.0, 68.0, 67.0, 66.0, 68.0, 66.0, 67.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.7}], "name": "kraken", "value": 1504.0}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.16816218012627], "unit": "score", "name": "Richards", "value": 212.4474360000001}, {"lowerIsBetter": false, "replicates": [190.29495718363464], "unit": "score", "name": "DeltaBlue", "value": 347.4500900000001}, {"lowerIsBetter": false, "replicates": [284.8191398461977], "unit": "score", "name": "Encrypt", "value": 934.5614910000002}, {"lowerIsBetter": false, "replicates": [5246.073298429319], "unit": "score", "name": "Decrypt", "value": 50.739092814371276}, {"lowerIsBetter": false, "replicates": [1145.475372279496], "unit": "score", "name": "RayTrace", "value": 646.010397}, {"lowerIsBetter": false, "replicates": [783.6990595611285], "unit": "score", "name": "Earley", "value": 850.4067880000003}, {"lowerIsBetter": false, "replicates": [12358.024691358025], "unit": "score", "name": "Boyer", "value": 53.929573426573455}, {"lowerIsBetter": false, "replicates": [43085.10638297872], "unit": "score", "name": "RegExp", "value": 21.14384938271604}, {"lowerIsBetter": false, "replicates": [625.3908692933084], "unit": "score", "name": "Splay", "value": 130.30410899999995}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 19800.589994398233}]} 05:04:40 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,35]]", "result": "19800.59"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,35]]", "result": "1504.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,35]]", "result": "6670.78"}}} 05:04:40 INFO - Return code: 0 05:04:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:04:40 INFO - # TBPL SUCCESS # 05:04:40 INFO - Running post-action listener: _resource_record_post_action 05:04:40 INFO - Running post-run listener: _resource_record_post_run 05:04:41 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 16384; Write bytes: 551673856; Read time: 48; Write time: 414596 05:04:41 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 239366144; Read time: 0; Write time: 210068 05:04:41 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 16384; Write bytes: 312307712; Read time: 48; Write time: 204528 05:04:41 INFO - Running post-run listener: _upload_blobber_files 05:04:41 INFO - Blob upload gear active. 05:04:41 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 05:04:41 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:04:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'B2G-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 05:04:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b B2G-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 05:04:41 INFO - (blobuploader) - INFO - Open directory for files ... 05:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 05:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:04:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:42 INFO - (blobuploader) - INFO - Done attempting. 05:04:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:04:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:04:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:04:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:04:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:04:43 INFO - (blobuploader) - INFO - Done attempting. 05:04:43 INFO - (blobuploader) - INFO - Iteration through files over. 05:04:43 INFO - Return code: 0 05:04:43 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 05:04:43 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 05:04:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/66e581453cf782c85363d0072d797325f8335cae9f35fe09419fa4f32f4e7b0f9398a2be941b4e64d03ae9d42b34ae9009fb36b0cbca221d24b1f9211aecce21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/fdc3ffd66f610fac3c8f08ffc3070978e8122c7e9f3ce7ac16e01a09ebaf9cb3150ceeeed3de1ab94bcf9b5f2e24207fa0e81f3fcef35c764db45f5d69da626a"} 05:04:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 05:04:43 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 05:04:43 INFO - Contents: 05:04:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/66e581453cf782c85363d0072d797325f8335cae9f35fe09419fa4f32f4e7b0f9398a2be941b4e64d03ae9d42b34ae9009fb36b0cbca221d24b1f9211aecce21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/fdc3ffd66f610fac3c8f08ffc3070978e8122c7e9f3ce7ac16e01a09ebaf9cb3150ceeeed3de1ab94bcf9b5f2e24207fa0e81f3fcef35c764db45f5d69da626a"} 05:04:43 INFO - Copying logs to upload dir... 05:04:43 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1277.110541 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-01-12 05:04:43.506235) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-12 05:04:43.509676) ========= 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=1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490 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/66e581453cf782c85363d0072d797325f8335cae9f35fe09419fa4f32f4e7b0f9398a2be941b4e64d03ae9d42b34ae9009fb36b0cbca221d24b1f9211aecce21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/fdc3ffd66f610fac3c8f08ffc3070978e8122c7e9f3ce7ac16e01a09ebaf9cb3150ceeeed3de1ab94bcf9b5f2e24207fa0e81f3fcef35c764db45f5d69da626a"} build_url:https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/YGYqLs_LRkW5Z5cpqNfbeg/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/66e581453cf782c85363d0072d797325f8335cae9f35fe09419fa4f32f4e7b0f9398a2be941b4e64d03ae9d42b34ae9009fb36b0cbca221d24b1f9211aecce21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/B2G-Inbound/sha512/fdc3ffd66f610fac3c8f08ffc3070978e8122c7e9f3ce7ac16e01a09ebaf9cb3150ceeeed3de1ab94bcf9b5f2e24207fa0e81f3fcef35c764db45f5d69da626a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-12 05:04:43.534803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 05:04:43.535144) ========= 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=1f5564757af27664ca02d71f000001b0-1452602308.699606-9593490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 05:04:43.621370) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 05:04:43.621705) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 05:04:43.622022) ========= ========= Total master_lag: 0.22 =========