builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-065 starttime: 1456766470.09 results: success (0) buildid: 20160229064712 builduid: 532317bd74944ffe99970626d4075ae1 revision: ee780025bbf46a77cda01fc041c8a0bb1c3ab047 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.086923) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.087942) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.088737) ========= 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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005621 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.114878) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.115543) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.131204) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.131884) ========= 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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004602 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.185334) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.186030) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.186828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.187543) ========= 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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-29 09:21:11-- 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% 83.6M=0s 2016-02-29 09:21:12 (83.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099814 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.301393) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.301744) ========= 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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021713 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:10.351401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-29 09:21:10.351870) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ee780025bbf46a77cda01fc041c8a0bb1c3ab047 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ee780025bbf46a77cda01fc041c8a0bb1c3ab047 --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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-29 09:21:12,151 truncating revision to first 12 chars 2016-02-29 09:21:12,151 Setting DEBUG logging. 2016-02-29 09:21:12,151 attempt 1/10 2016-02-29 09:21:12,152 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ee780025bbf4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-29 09:21:13,360 unpacking tar archive at: mozilla-aurora-ee780025bbf4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.538098 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-29 09:21:11.904317) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:11.905158) ========= echo ee780025bbf46a77cda01fc041c8a0bb1c3ab047 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'ee780025bbf46a77cda01fc041c8a0bb1c3ab047'] 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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False ee780025bbf46a77cda01fc041c8a0bb1c3ab047 program finished with exit code 0 elapsedTime=0.004369 script_repo_revision: 'ee780025bbf46a77cda01fc041c8a0bb1c3ab047' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:11.959196) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:11.959914) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-29 09:21:11.970939) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-02-29 09:21:11.971240) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:21:13 INFO - MultiFileLogger online at 20160229 09:21:13 in /builds/slave/test-pgo 09:21:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:21:13 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:21:13 INFO - {'append_to_log': False, 09:21:13 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:21:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:21:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:21:13 INFO - 'branch': 'Mozilla-Aurora', 09:21:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:21:13 INFO - 'config_files': ('talos/linux_config.py',), 09:21:13 INFO - 'default_actions': ('clobber', 09:21:13 INFO - 'read-buildbot-config', 09:21:13 INFO - 'download-and-extract', 09:21:13 INFO - 'populate-webroot', 09:21:13 INFO - 'create-virtualenv', 09:21:13 INFO - 'install', 09:21:13 INFO - 'run-tests'), 09:21:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:21:13 INFO - 'download_minidump_stackwalk': True, 09:21:13 INFO - 'download_symbols': 'ondemand', 09:21:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:21:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:21:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:21:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:21:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:21:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:21:13 INFO - 'installer_path': 'installer.exe', 09:21:13 INFO - 'log_level': 'info', 09:21:13 INFO - 'log_name': 'talos', 09:21:13 INFO - 'log_to_console': True, 09:21:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:21:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:21:13 INFO - 'opt_config_files': (), 09:21:13 INFO - 'pip_index': False, 09:21:13 INFO - 'sps_profile': False, 09:21:13 INFO - 'sps_profile_interval': 0, 09:21:13 INFO - 'suite': 'dromaeojs-e10s', 09:21:13 INFO - 'system_bits': '32', 09:21:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:21:13 INFO - 'title': 'talos-linux64-ix-065', 09:21:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:21:13 INFO - 'use_talos_json': True, 09:21:13 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:21:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:21:13 INFO - 'work_dir': 'build'} 09:21:13 INFO - ##### 09:21:13 INFO - ##### Running clobber step. 09:21:13 INFO - ##### 09:21:13 INFO - Running pre-action listener: _resource_record_pre_action 09:21:13 INFO - Running main action method: clobber 09:21:13 INFO - rmtree: /builds/slave/test-pgo/build 09:21:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:21:14 INFO - Running post-action listener: _resource_record_post_action 09:21:14 INFO - ##### 09:21:14 INFO - ##### Running read-buildbot-config step. 09:21:14 INFO - ##### 09:21:14 INFO - Running pre-action listener: _resource_record_pre_action 09:21:14 INFO - Running main action method: read_buildbot_config 09:21:14 INFO - Using buildbot properties: 09:21:14 INFO - { 09:21:14 INFO - "project": "", 09:21:14 INFO - "product": "firefox", 09:21:14 INFO - "script_repo_revision": "production", 09:21:14 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 09:21:14 INFO - "repository": "", 09:21:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 09:21:14 INFO - "buildid": "20160229064712", 09:21:14 INFO - "pgo_build": "True", 09:21:14 INFO - "basedir": "/builds/slave/test-pgo", 09:21:14 INFO - "buildnumber": 183, 09:21:14 INFO - "slavename": "talos-linux64-ix-065", 09:21:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:21:14 INFO - "platform": "ubuntu64_hw", 09:21:14 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:21:14 INFO - "branch": "mozilla-aurora", 09:21:14 INFO - "repo_path": "releases/mozilla-aurora", 09:21:14 INFO - "revision": "ee780025bbf46a77cda01fc041c8a0bb1c3ab047", 09:21:14 INFO - "stage_platform": "linux64-pgo", 09:21:14 INFO - "builduid": "532317bd74944ffe99970626d4075ae1", 09:21:14 INFO - "slavebuilddir": "test" 09:21:14 INFO - } 09:21:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2. 09:21:14 INFO - Running post-action listener: _resource_record_post_action 09:21:14 INFO - ##### 09:21:14 INFO - ##### Running download-and-extract step. 09:21:14 INFO - ##### 09:21:14 INFO - Running pre-action listener: _resource_record_pre_action 09:21:14 INFO - Running main action method: download_and_extract 09:21:14 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:21:14 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')]} 09:21:14 INFO - https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:21:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/test_packages.json 09:21:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/test_packages.json 09:21:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:21:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:21:15 INFO - Downloaded 1448 bytes. 09:21:15 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:21:15 INFO - Using the following test package requirements: 09:21:15 INFO - {u'common': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 09:21:15 INFO - u'cppunittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:21:15 INFO - u'firefox-46.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:21:15 INFO - u'gtest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:21:15 INFO - u'firefox-46.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:21:15 INFO - u'jittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:21:15 INFO - u'jsshell-linux-x86_64.zip'], 09:21:15 INFO - u'mochitest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:21:15 INFO - u'firefox-46.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:21:15 INFO - u'mozbase': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 09:21:15 INFO - u'reftest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:21:15 INFO - u'firefox-46.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:21:15 INFO - u'talos': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:21:15 INFO - u'firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:21:15 INFO - u'web-platform': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:21:15 INFO - u'firefox-46.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:21:15 INFO - u'webapprt': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 09:21:15 INFO - u'xpcshell': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:21:15 INFO - u'firefox-46.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:21:15 INFO - Downloading packages: [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:15 INFO - https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:21:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:21:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:21:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:21:16 INFO - Downloaded 23081916 bytes. 09:21:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 09:21:16 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 09:21:17 INFO - Return code: 0 09:21:17 INFO - Downloading packages: [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:21:17 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')]} 09:21:17 INFO - https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:21:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:21:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:21:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:21:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:21:18 INFO - Downloaded 23081916 bytes. 09:21:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 09:21:18 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 09:21:19 INFO - Return code: 0 09:21:19 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')]} 09:21:19 INFO - https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:21:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 09:21:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 09:21:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 09:21:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:21:20 INFO - Downloaded 10706649 bytes. 09:21:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 09:21:20 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 09:21:21 INFO - Return code: 0 09:21:21 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')]} 09:21:21 INFO - https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:21:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:21:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:21:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:21:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:21:22 INFO - Downloaded 59967367 bytes. 09:21:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:21:22 INFO - mkdir: /builds/slave/test-pgo/properties 09:21:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:21:22 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:21:22 INFO - Contents: 09:21:22 INFO - build_url:https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:21:23 INFO - Running post-action listener: _resource_record_post_action 09:21:23 INFO - Running post-action listener: set_extra_try_arguments 09:21:23 INFO - ##### 09:21:23 INFO - ##### Running populate-webroot step. 09:21:23 INFO - ##### 09:21:23 INFO - Running pre-action listener: _resource_record_pre_action 09:21:23 INFO - Running main action method: populate_webroot 09:21:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:21:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:21:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:21:23 INFO - '--tppagecycles', 09:21:23 INFO - '7'], 09:21:23 INFO - 'tests': ['tsvgm']}}, 09:21:23 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:21:23 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:21:23 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:21:23 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:21:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:23 INFO - 'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:23 INFO - 'tests': ['damp', 'tps']}, 09:21:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:23 INFO - 'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['damp', 'tps']}, 09:21:23 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:21:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:21:23 INFO - 'other': {'tests': ['a11yr', 09:21:23 INFO - 'ts_paint', 09:21:23 INFO - 'tpaint', 09:21:23 INFO - 'sessionrestore', 09:21:23 INFO - 'sessionrestore_no_auto_restore']}, 09:21:23 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['a11yr', 09:21:23 INFO - 'ts_paint', 09:21:23 INFO - 'tpaint', 09:21:23 INFO - 'sessionrestore', 09:21:23 INFO - 'sessionrestore_no_auto_restore']}, 09:21:23 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['a11yr', 09:21:23 INFO - 'ts_paint', 09:21:23 INFO - 'tpaint', 09:21:23 INFO - 'sessionrestore', 09:21:23 INFO - 'sessionrestore_no_auto_restore']}, 09:21:23 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['a11yr', 09:21:23 INFO - 'ts_paint', 09:21:23 INFO - 'tpaint', 09:21:23 INFO - 'sessionrestore', 09:21:23 INFO - 'sessionrestore_no_auto_restore']}, 09:21:23 INFO - 'other_l64': {'tests': ['a11yr', 09:21:23 INFO - 'ts_paint', 09:21:23 INFO - 'tpaint', 09:21:23 INFO - 'sessionrestore', 09:21:23 INFO - 'sessionrestore_no_auto_restore']}, 09:21:23 INFO - 'other_nol64': {'tests': ['a11yr', 09:21:23 INFO - 'ts_paint', 09:21:23 INFO - 'tpaint', 09:21:23 INFO - 'sessionrestore', 09:21:23 INFO - 'sessionrestore_no_auto_restore']}, 09:21:23 INFO - 'svgr': {'tests': ['tsvgx', 09:21:23 INFO - 'tsvgr_opacity', 09:21:23 INFO - 'tart', 09:21:23 INFO - 'tscrollx', 09:21:23 INFO - 'cart']}, 09:21:23 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['tsvgx', 09:21:23 INFO - 'tsvgr_opacity', 09:21:23 INFO - 'tart', 09:21:23 INFO - 'tscrollx', 09:21:23 INFO - 'cart']}, 09:21:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:23 INFO - 'tests': ['tp5o']}, 09:21:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:23 INFO - 'talos_options': ['--e10s'], 09:21:23 INFO - 'tests': ['tp5o']}, 09:21:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:23 INFO - 'talos_options': ['--xperf_path', 09:21:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:23 INFO - 'tests': ['tp5n']}, 09:21:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:23 INFO - 'talos_options': ['--e10s', 09:21:23 INFO - '--xperf_path', 09:21:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:23 INFO - 'tests': ['tp5n']}}, 09:21:23 INFO - 'talos.zip': {'path': '', 09:21:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:21:23 INFO - Running post-action listener: _resource_record_post_action 09:21:23 INFO - ##### 09:21:23 INFO - ##### Running create-virtualenv step. 09:21:23 INFO - ##### 09:21:23 INFO - Running pre-action listener: _resource_record_pre_action 09:21:23 INFO - Running main action method: create_virtualenv 09:21:23 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:21:23 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 09:21:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:21:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:21:23 INFO - Using real prefix '/usr' 09:21:23 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:21:23 INFO - Installing distribute.............................................................................................................................................................................................done. 09:21:25 INFO - Installing pip.................done. 09:21:25 INFO - Return code: 0 09:21:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:25 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 09:21:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:21:25 INFO - Using env: {'DISPLAY': ':0', 09:21:25 INFO - 'HOME': '/home/cltbld', 09:21:25 INFO - 'LANG': 'en_US.UTF-8', 09:21:25 INFO - 'LANGUAGE': 'en_US:en', 09:21:25 INFO - 'LOGNAME': 'cltbld', 09:21:25 INFO - 'MAIL': '/var/mail/cltbld', 09:21:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:25 INFO - 'MOZ_NO_REMOTE': '1', 09:21:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:25 INFO - 'NO_EM_RESTART': '1', 09:21:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:25 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:25 INFO - 'SHELL': '/bin/bash', 09:21:25 INFO - 'SHLVL': '1', 09:21:25 INFO - 'TERM': 'linux', 09:21:25 INFO - 'TMOUT': '86400', 09:21:25 INFO - 'USER': 'cltbld', 09:21:25 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:25 INFO - '_': '/tools/buildbot/bin/python'} 09:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:26 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 09:21:26 INFO - Cleaning up... 09:21:26 INFO - Return code: 0 09:21:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:21:26 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')]} 09:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:26 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')]} 09:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:26 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 09:21:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:21:26 INFO - Using env: {'DISPLAY': ':0', 09:21:26 INFO - 'HOME': '/home/cltbld', 09:21:26 INFO - 'LANG': 'en_US.UTF-8', 09:21:26 INFO - 'LANGUAGE': 'en_US:en', 09:21:26 INFO - 'LOGNAME': 'cltbld', 09:21:26 INFO - 'MAIL': '/var/mail/cltbld', 09:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:26 INFO - 'MOZ_NO_REMOTE': '1', 09:21:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:26 INFO - 'NO_EM_RESTART': '1', 09:21:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:26 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:26 INFO - 'SHELL': '/bin/bash', 09:21:26 INFO - 'SHLVL': '1', 09:21:26 INFO - 'TERM': 'linux', 09:21:26 INFO - 'TMOUT': '86400', 09:21:26 INFO - 'USER': 'cltbld', 09:21:26 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:26 INFO - '_': '/tools/buildbot/bin/python'} 09:21:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:26 INFO - Downloading/unpacking psutil>=0.7.1 09:21:26 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 09:21:26 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 09:21:26 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 09:21:26 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 09:21:26 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 09:21:26 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 09:21:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:28 INFO - Installing collected packages: psutil 09:21:28 INFO - Running setup.py install for psutil 09:21:28 INFO - building 'psutil._psutil_linux' extension 09:21:28 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 09:21:28 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 09:21:28 INFO - building 'psutil._psutil_posix' extension 09:21:28 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 09:21:28 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 09:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:28 INFO - Successfully installed psutil 09:21:28 INFO - Cleaning up... 09:21:28 INFO - Return code: 0 09:21:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:28 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 09:21:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:21:28 INFO - Using env: {'DISPLAY': ':0', 09:21:28 INFO - 'HOME': '/home/cltbld', 09:21:28 INFO - 'LANG': 'en_US.UTF-8', 09:21:28 INFO - 'LANGUAGE': 'en_US:en', 09:21:28 INFO - 'LOGNAME': 'cltbld', 09:21:28 INFO - 'MAIL': '/var/mail/cltbld', 09:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:28 INFO - 'MOZ_NO_REMOTE': '1', 09:21:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:28 INFO - 'NO_EM_RESTART': '1', 09:21:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:28 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:28 INFO - 'SHELL': '/bin/bash', 09:21:28 INFO - 'SHLVL': '1', 09:21:28 INFO - 'TERM': 'linux', 09:21:28 INFO - 'TMOUT': '86400', 09:21:28 INFO - 'USER': 'cltbld', 09:21:28 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:28 INFO - '_': '/tools/buildbot/bin/python'} 09:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:21:28 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 09:21:28 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 09:21:28 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 09:21:28 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 09:21:28 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 09:21:28 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 09:21:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:21:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:21:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:21:30 INFO - Installing collected packages: mozsystemmonitor 09:21:30 INFO - Running setup.py install for mozsystemmonitor 09:21:30 INFO - Successfully installed mozsystemmonitor 09:21:30 INFO - Cleaning up... 09:21:30 INFO - Return code: 0 09:21:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:21:30 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')]} 09:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:30 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')]} 09:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:30 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 09:21:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:21:30 INFO - Using env: {'DISPLAY': ':0', 09:21:30 INFO - 'HOME': '/home/cltbld', 09:21:30 INFO - 'LANG': 'en_US.UTF-8', 09:21:30 INFO - 'LANGUAGE': 'en_US:en', 09:21:30 INFO - 'LOGNAME': 'cltbld', 09:21:30 INFO - 'MAIL': '/var/mail/cltbld', 09:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:30 INFO - 'MOZ_NO_REMOTE': '1', 09:21:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:30 INFO - 'NO_EM_RESTART': '1', 09:21:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:31 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:31 INFO - 'SHELL': '/bin/bash', 09:21:31 INFO - 'SHLVL': '1', 09:21:31 INFO - 'TERM': 'linux', 09:21:31 INFO - 'TMOUT': '86400', 09:21:31 INFO - 'USER': 'cltbld', 09:21:31 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:31 INFO - '_': '/tools/buildbot/bin/python'} 09:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:31 INFO - Downloading/unpacking blobuploader==1.2.4 09:21:31 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 09:21:31 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 09:21:31 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 09:21:31 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 09:21:31 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 09:21:31 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 09:21:32 INFO - Downloading blobuploader-1.2.4.tar.gz 09:21:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:21:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:21:33 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 09:21:33 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 09:21:33 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 09:21:33 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 09:21:33 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 09:21:33 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 09:21:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:21:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:21:33 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 09:21:33 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 09:21:33 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 09:21:33 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 09:21:33 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 09:21:33 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 09:21:33 INFO - Downloading docopt-0.6.1.tar.gz 09:21:33 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:21:33 INFO - Installing collected packages: blobuploader, requests, docopt 09:21:33 INFO - Running setup.py install for blobuploader 09:21:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:21:33 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:21:33 INFO - Running setup.py install for requests 09:21:34 INFO - Running setup.py install for docopt 09:21:34 INFO - Successfully installed blobuploader requests docopt 09:21:34 INFO - Cleaning up... 09:21:34 INFO - Return code: 0 09:21:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:21:34 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')]} 09:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:34 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')]} 09:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:34 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:21:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:34 INFO - Using env: {'DISPLAY': ':0', 09:21:34 INFO - 'HOME': '/home/cltbld', 09:21:34 INFO - 'LANG': 'en_US.UTF-8', 09:21:34 INFO - 'LANGUAGE': 'en_US:en', 09:21:34 INFO - 'LOGNAME': 'cltbld', 09:21:34 INFO - 'MAIL': '/var/mail/cltbld', 09:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:34 INFO - 'MOZ_NO_REMOTE': '1', 09:21:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:34 INFO - 'NO_EM_RESTART': '1', 09:21:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:34 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:34 INFO - 'SHELL': '/bin/bash', 09:21:34 INFO - 'SHLVL': '1', 09:21:34 INFO - 'TERM': 'linux', 09:21:34 INFO - 'TMOUT': '86400', 09:21:34 INFO - 'USER': 'cltbld', 09:21:34 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:34 INFO - '_': '/tools/buildbot/bin/python'} 09:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:21:34 INFO - Running setup.py (path:/tmp/pip-Sr5CAn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:21:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:21:34 INFO - Running setup.py (path:/tmp/pip-dfnwnt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:21:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:21:34 INFO - Running setup.py (path:/tmp/pip-GBS_88-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:21:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:21:34 INFO - Running setup.py (path:/tmp/pip-XaxM71-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:21:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:21:34 INFO - Running setup.py (path:/tmp/pip-rzNadf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:21:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:21:34 INFO - Running setup.py (path:/tmp/pip-7DV8aY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:21:35 INFO - Running setup.py (path:/tmp/pip-czteMl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:21:35 INFO - Running setup.py (path:/tmp/pip-ESqGmj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:21:35 INFO - Running setup.py (path:/tmp/pip-2sGCNW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:21:35 INFO - Running setup.py (path:/tmp/pip-4UNkAm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:21:35 INFO - Running setup.py (path:/tmp/pip-4QF4Co-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:21:35 INFO - Running setup.py (path:/tmp/pip-Mr0gbS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:21:35 INFO - Running setup.py (path:/tmp/pip-nfxI5y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:21:35 INFO - Running setup.py (path:/tmp/pip-hFTFTV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:21:35 INFO - Running setup.py (path:/tmp/pip-ESChDP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:21:35 INFO - Running setup.py (path:/tmp/pip-mU9Er9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:21:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:21:35 INFO - Running setup.py (path:/tmp/pip-8lBvvD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:21:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:21:35 INFO - Running setup.py install for manifestparser 09:21:35 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:21:35 INFO - Running setup.py install for mozcrash 09:21:36 INFO - Running setup.py install for mozdebug 09:21:36 INFO - Running setup.py install for mozdevice 09:21:36 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Running setup.py install for mozfile 09:21:36 INFO - Running setup.py install for mozhttpd 09:21:36 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Running setup.py install for mozinfo 09:21:36 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Running setup.py install for mozInstall 09:21:36 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Running setup.py install for mozleak 09:21:36 INFO - Running setup.py install for mozlog 09:21:36 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Running setup.py install for moznetwork 09:21:36 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:21:36 INFO - Running setup.py install for mozprocess 09:21:37 INFO - Running setup.py install for mozprofile 09:21:37 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:21:37 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:21:37 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:21:37 INFO - Running setup.py install for mozrunner 09:21:37 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:21:37 INFO - Running setup.py install for mozscreenshot 09:21:37 INFO - Running setup.py install for moztest 09:21:37 INFO - Running setup.py install for mozversion 09:21:37 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:21:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:21:37 INFO - Cleaning up... 09:21:37 INFO - Return code: 0 09:21:37 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:21:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 09:21:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:37 INFO - Using env: {'DISPLAY': ':0', 09:21:37 INFO - 'HOME': '/home/cltbld', 09:21:37 INFO - 'LANG': 'en_US.UTF-8', 09:21:37 INFO - 'LANGUAGE': 'en_US:en', 09:21:37 INFO - 'LOGNAME': 'cltbld', 09:21:37 INFO - 'MAIL': '/var/mail/cltbld', 09:21:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:37 INFO - 'MOZ_NO_REMOTE': '1', 09:21:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:37 INFO - 'NO_EM_RESTART': '1', 09:21:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:37 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:37 INFO - 'SHELL': '/bin/bash', 09:21:37 INFO - 'SHLVL': '1', 09:21:37 INFO - 'TERM': 'linux', 09:21:37 INFO - 'TMOUT': '86400', 09:21:37 INFO - 'USER': 'cltbld', 09:21:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:37 INFO - '_': '/tools/buildbot/bin/python'} 09:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:21:37 INFO - Running setup.py (path:/tmp/pip-zigdqA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:21:37 INFO - Running setup.py (path:/tmp/pip-f4oAVW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:21:37 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)) 09:21:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:21:37 INFO - Running setup.py (path:/tmp/pip-KLTaHb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:21:38 INFO - Running setup.py (path:/tmp/pip-PnfHd3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:21:38 INFO - Running setup.py (path:/tmp/pip-jpTsPf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:21:38 INFO - Running setup.py (path:/tmp/pip-CzKM3C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:21:38 INFO - Running setup.py (path:/tmp/pip-d5lNx_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:21:38 INFO - Running setup.py (path:/tmp/pip-C9RAQG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:21:38 INFO - Running setup.py (path:/tmp/pip-OWqbOz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:21:38 INFO - Running setup.py (path:/tmp/pip-o0gg93-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:21:38 INFO - Running setup.py (path:/tmp/pip-fyveNh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:21:38 INFO - Running setup.py (path:/tmp/pip-OCzOLb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:21:38 INFO - Running setup.py (path:/tmp/pip-cyq_dl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:21:38 INFO - Running setup.py (path:/tmp/pip-1opTJO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:21:38 INFO - Running setup.py (path:/tmp/pip-Z3fQpl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:21:38 INFO - Running setup.py (path:/tmp/pip-rifIYr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:21:38 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)) 09:21:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:21:38 INFO - Running setup.py (path:/tmp/pip-wtHiic-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 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)) 09:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:21:39 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 09:21:39 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 09:21:39 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 09:21:39 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 09:21:39 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 09:21:39 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 09:21:40 INFO - Downloading blessings-1.6.tar.gz 09:21:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:21:40 INFO - Installing collected packages: blessings 09:21:40 INFO - Running setup.py install for blessings 09:21:41 INFO - Successfully installed blessings 09:21:41 INFO - Cleaning up... 09:21:41 INFO - Return code: 0 09:21:41 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:21:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:21:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:21:41 INFO - Reading from file tmpfile_stdout 09:21:41 INFO - Current package versions: 09:21:41 INFO - argparse == 1.2.1 09:21:41 INFO - blessings == 1.6 09:21:41 INFO - blobuploader == 1.2.4 09:21:41 INFO - docopt == 0.6.1 09:21:41 INFO - manifestparser == 1.1 09:21:41 INFO - mozInstall == 1.12 09:21:41 INFO - mozcrash == 0.16 09:21:41 INFO - mozdebug == 0.1 09:21:41 INFO - mozdevice == 0.48 09:21:41 INFO - mozfile == 1.2 09:21:41 INFO - mozhttpd == 0.7 09:21:41 INFO - mozinfo == 0.9 09:21:41 INFO - mozleak == 0.1 09:21:41 INFO - mozlog == 3.1 09:21:41 INFO - moznetwork == 0.27 09:21:41 INFO - mozprocess == 0.22 09:21:41 INFO - mozprofile == 0.28 09:21:41 INFO - mozrunner == 6.11 09:21:41 INFO - mozscreenshot == 0.1 09:21:41 INFO - mozsystemmonitor == 0.0 09:21:41 INFO - moztest == 0.7 09:21:41 INFO - mozversion == 1.4 09:21:41 INFO - psutil == 3.1.1 09:21:41 INFO - requests == 1.2.3 09:21:41 INFO - wsgiref == 0.1.2 09:21:41 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:21:41 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')]} 09:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:41 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')]} 09:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 09:21:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:41 INFO - Using env: {'DISPLAY': ':0', 09:21:41 INFO - 'HOME': '/home/cltbld', 09:21:41 INFO - 'LANG': 'en_US.UTF-8', 09:21:41 INFO - 'LANGUAGE': 'en_US:en', 09:21:41 INFO - 'LOGNAME': 'cltbld', 09:21:41 INFO - 'MAIL': '/var/mail/cltbld', 09:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:41 INFO - 'MOZ_NO_REMOTE': '1', 09:21:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:41 INFO - 'NO_EM_RESTART': '1', 09:21:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:41 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:41 INFO - 'SHELL': '/bin/bash', 09:21:41 INFO - 'SHLVL': '1', 09:21:41 INFO - 'TERM': 'linux', 09:21:41 INFO - 'TMOUT': '86400', 09:21:41 INFO - 'USER': 'cltbld', 09:21:41 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:41 INFO - '_': '/tools/buildbot/bin/python'} 09:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 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)) 09:21:41 INFO - Cleaning up... 09:21:41 INFO - Return code: 0 09:21:41 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:21:41 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')]} 09:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:41 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')]} 09:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 09:21:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:21:41 INFO - Using env: {'DISPLAY': ':0', 09:21:41 INFO - 'HOME': '/home/cltbld', 09:21:41 INFO - 'LANG': 'en_US.UTF-8', 09:21:41 INFO - 'LANGUAGE': 'en_US:en', 09:21:41 INFO - 'LOGNAME': 'cltbld', 09:21:41 INFO - 'MAIL': '/var/mail/cltbld', 09:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:41 INFO - 'MOZ_NO_REMOTE': '1', 09:21:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:41 INFO - 'NO_EM_RESTART': '1', 09:21:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:41 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:41 INFO - 'SHELL': '/bin/bash', 09:21:41 INFO - 'SHLVL': '1', 09:21:41 INFO - 'TERM': 'linux', 09:21:41 INFO - 'TMOUT': '86400', 09:21:41 INFO - 'USER': 'cltbld', 09:21:41 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:41 INFO - '_': '/tools/buildbot/bin/python'} 09:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:41 INFO - Downloading/unpacking jsonschema 09:21:41 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 09:21:41 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 09:21:41 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 09:21:41 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 09:21:41 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 09:21:41 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 09:21:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:21:43 INFO - Downloading/unpacking functools32 (from jsonschema) 09:21:43 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 09:21:43 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 09:21:43 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 09:21:43 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 09:21:43 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 09:21:43 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 09:21:43 INFO - Downloading functools32-3.2.3-2.tar.gz 09:21:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:21:43 INFO - warning: no files found matching '*.txt' 09:21:43 INFO - no previously-included directories found matching 'build' 09:21:43 INFO - no previously-included directories found matching 'dist' 09:21:43 INFO - no previously-included directories found matching '.git*' 09:21:43 INFO - Installing collected packages: jsonschema, functools32 09:21:43 INFO - Running setup.py install for functools32 09:21:43 INFO - warning: no files found matching '*.txt' 09:21:43 INFO - no previously-included directories found matching 'build' 09:21:43 INFO - no previously-included directories found matching 'dist' 09:21:43 INFO - no previously-included directories found matching '.git*' 09:21:43 INFO - Successfully installed jsonschema functools32 09:21:43 INFO - Cleaning up... 09:21:44 INFO - Return code: 0 09:21:44 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8672b42f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2398230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, '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': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', '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 09:21:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 09:21:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:21:44 INFO - Using env: {'DISPLAY': ':0', 09:21:44 INFO - 'HOME': '/home/cltbld', 09:21:44 INFO - 'LANG': 'en_US.UTF-8', 09:21:44 INFO - 'LANGUAGE': 'en_US:en', 09:21:44 INFO - 'LOGNAME': 'cltbld', 09:21:44 INFO - 'MAIL': '/var/mail/cltbld', 09:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:44 INFO - 'MOZ_NO_REMOTE': '1', 09:21:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:21:44 INFO - 'NO_EM_RESTART': '1', 09:21:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:21:44 INFO - 'PWD': '/builds/slave/test-pgo', 09:21:44 INFO - 'SHELL': '/bin/bash', 09:21:44 INFO - 'SHLVL': '1', 09:21:44 INFO - 'TERM': 'linux', 09:21:44 INFO - 'TMOUT': '86400', 09:21:44 INFO - 'USER': 'cltbld', 09:21:44 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:44 INFO - '_': '/tools/buildbot/bin/python'} 09:21:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:44 INFO - Downloading/unpacking flake8 09:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:21:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:21:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 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 09:21:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:21:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:21:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:21:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:21:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:21:46 INFO - no previously-included directories found matching 'docs/_build' 09:21:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:21:46 INFO - Running setup.py install for pep8 09:21:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:21:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:21:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:21:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:21:46 INFO - no previously-included directories found matching 'docs/_build' 09:21:46 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:21:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:21:46 INFO - Cleaning up... 09:21:46 INFO - Return code: 0 09:21:46 INFO - Running post-action listener: _resource_record_post_action 09:21:46 INFO - Running post-action listener: _start_resource_monitoring 09:21:47 INFO - Starting resource monitoring. 09:21:47 INFO - ##### 09:21:47 INFO - ##### Running install step. 09:21:47 INFO - ##### 09:21:47 INFO - Running pre-action listener: _resource_record_pre_action 09:21:47 INFO - Running main action method: install 09:21:47 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:21:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:21:47 INFO - Reading from file tmpfile_stdout 09:21:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:21:47 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:21:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:21:47 INFO - Reading from file tmpfile_stdout 09:21:47 INFO - Output received: 09:21:47 INFO - Usage: mozinstall [options] installer 09:21:47 INFO - Options: 09:21:47 INFO - -h, --help show this help message and exit 09:21:47 INFO - -d DEST, --destination=DEST 09:21:47 INFO - Directory to install application into. [default: 09:21:47 INFO - "/builds/slave/test-pgo"] 09:21:47 INFO - --app=APP Application being installed. [default: firefox] 09:21:47 INFO - mkdir: /builds/slave/test-pgo/build/application 09:21:47 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 09:21:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 09:22:02 INFO - Reading from file tmpfile_stdout 09:22:02 INFO - Output received: 09:22:02 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:22:02 INFO - Running post-action listener: _resource_record_post_action 09:22:02 INFO - ##### 09:22:02 INFO - ##### Running run-tests step. 09:22:02 INFO - ##### 09:22:02 INFO - Running pre-action listener: _resource_record_pre_action 09:22:02 INFO - Running main action method: run_tests 09:22:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:22:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:22:02 INFO - Python 2.7.3 09:22:02 INFO - Return code: 0 09:22:02 INFO - grabbing minidump binary from tooltool 09:22: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')]} 09:22:02 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 0x242a7f0>, '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 0x23962d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2396760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:22:02 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 09:22:02 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 09:22:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:22:02 INFO - Return code: 0 09:22:02 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:22:02 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:22:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:22:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:22:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:22:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:22:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:22:03 INFO - Return code: 0 09:22:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 09:22:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:22:03 INFO - Using env: {'DISPLAY': ':0', 09:22:03 INFO - 'HOME': '/home/cltbld', 09:22:03 INFO - 'LANG': 'en_US.UTF-8', 09:22:03 INFO - 'LANGUAGE': 'en_US:en', 09:22:03 INFO - 'LOGNAME': 'cltbld', 09:22:03 INFO - 'MAIL': '/var/mail/cltbld', 09:22:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:22:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:03 INFO - 'MOZ_NO_REMOTE': '1', 09:22:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:22:03 INFO - 'NO_EM_RESTART': '1', 09:22:03 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:22:03 INFO - 'PWD': '/builds/slave/test-pgo', 09:22:03 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:22:03 INFO - 'SHELL': '/bin/bash', 09:22:03 INFO - 'SHLVL': '1', 09:22:03 INFO - 'TERM': 'linux', 09:22:03 INFO - 'TMOUT': '86400', 09:22:03 INFO - 'USER': 'cltbld', 09:22:03 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087', 09:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:03 INFO - '_': '/tools/buildbot/bin/python'} 09:22:03 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:22:03 INFO - mozversion application_buildid: 20160229064712 09:22:03 INFO - mozversion application_changeset: ee780025bbf46a77cda01fc041c8a0bb1c3ab047 09:22:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:22:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:22:03 INFO - mozversion application_name: Firefox 09:22:03 INFO - mozversion application_remotingname: firefox-dev 09:22:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:03 INFO - mozversion application_vendor: Mozilla 09:22:03 INFO - mozversion application_version: 46.0a2 09:22:03 INFO - mozversion platform_buildid: 20160229064712 09:22:03 INFO - mozversion platform_changeset: ee780025bbf46a77cda01fc041c8a0bb1c3ab047 09:22:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:03 INFO - mozversion platform_version: 46.0a2 09:22:03 INFO - using testdate: 1456766523 09:22:03 INFO - actual date: 1456766523 09:22:03 INFO - starting webserver on 'localhost:38071' 09:22:03 INFO - SUITE-START | Running 3 tests 09:22:03 INFO - TEST-START | dromaeo_css 09:22:03 INFO - operating with platform_type : linux_ 09:22:03 INFO - Initialising browser for dromaeo_css test... 09:22:03 INFO - TEST-INFO | started process 27187 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpB9rF3O/profile http://localhost:38071/getInfo.html) 09:22:07 INFO - PROCESS | 27187 | 09:22:07 INFO - PROCESS | 27187 | (process:27269): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:22:07 INFO - PROCESS | 27187 | __metrics Screen width/height:1600/1200 09:22:07 INFO - PROCESS | 27187 | colorDepth:24 09:22:07 INFO - PROCESS | 27187 | Browser inner width/height: 1024/697 09:22:07 INFO - PROCESS | 27187 | __metrics 09:22:08 INFO - TEST-INFO | 27187: exit 0 09:22:08 INFO - Browser initialized. 09:22:08 INFO - Running cycle 1/1 for dromaeo_css test... 09:22:08 INFO - TEST-INFO | started process 27319 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpB9rF3O/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:10 INFO - PROCESS | 27319 | 09:22:10 INFO - PROCESS | 27319 | (process:27380): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:22:20 INFO - PROCESS | 27319 | RSS: Main: 141492224 09:22:20 INFO - PROCESS | 27319 | 09:23:10 INFO - PROCESS | 27319 | 1456766590366 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] 09:25:18 INFO - PROCESS | 27319 | Cycle 1(1): loaded http://localhost:38071/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38071/tests/dromaeo/cssquery-ext.html) 09:25:19 INFO - PROCESS | 27319 | RSS: Main: 145633280 09:25:19 INFO - PROCESS | 27319 | 09:28:21 INFO - PROCESS | 27319 | Cycle 1(1): loaded http://localhost:38071/tests/dromaeo/cssquery-ext.html (next: http://localhost:38071/tests/dromaeo/cssquery-jquery.html) 09:28:21 INFO - PROCESS | 27319 | RSS: Main: 144285696 09:28:21 INFO - PROCESS | 27319 | 09:31:23 INFO - PROCESS | 27319 | Cycle 1(1): loaded http://localhost:38071/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38071/tests/dromaeo/cssquery-mootools.html) 09:31:24 INFO - PROCESS | 27319 | RSS: Main: 143118336 09:31:24 INFO - PROCESS | 27319 | 09:34:25 INFO - PROCESS | 27319 | Cycle 1(1): loaded http://localhost:38071/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38071/tests/dromaeo/cssquery-prototype.html) 09:34:26 INFO - PROCESS | 27319 | RSS: Main: 142569472 09:34:26 INFO - PROCESS | 27319 | 09:37:28 INFO - PROCESS | 27319 | Cycle 1(1): loaded http://localhost:38071/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38071/tests/dromaeo/cssquery-yui.html) 09:37:29 INFO - PROCESS | 27319 | RSS: Main: 143597568 09:37:29 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | Cycle 1(1): loaded http://localhost:38071/tests/dromaeo/cssquery-yui.html (next: http://localhost:38071/tests/dromaeo/cssquery-dojo.html) 09:40:31 INFO - PROCESS | 27319 | RSS: Main: 146878464 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | __start_tp_report 09:40:31 INFO - PROCESS | 27319 | _x_x_mozilla_page_load 09:40:31 INFO - PROCESS | 27319 | _x_x_mozilla_page_load_details 09:40:31 INFO - PROCESS | 27319 | |i|pagename|runs| 09:40:31 INFO - PROCESS | 27319 | |0;dojo.html;3326.168457885529;3336.866525338988;3351.514030465581;3365.9663403365976;3376.662333766623;5345.679259244448;5363.785448582057;5365.973170134153;5377.731113444327;5390.838274851756;5353.0899747042995;5356.223347614603;5374.220737992989;5387.380451248107;5399.217113518547;4929.7781599828;4947.851564453073;4997.875053123683;4999.925001124986;5093.337866077423;4792.849716068136;4871.464138944713;4951.059298733229;4956.157453232959;4956.40523137224;4948.901021979558;4967.403911530621;4993.6254780891395;5009.57418619416;5014.272930425086;4941.579965702919;4958.033183529214;4964.975175124119;4966.627502937295;5003.774830132635;4810.326554282403;4840.636952228561;4890.290907818382;4932.96407754372;4936;5118.539331460149;5149.150390185615;5149.510796474329;5157.58739300855;5175.171972484384;5759.971200144015;5776.451237132465;5797.304323481167;5807.796727114531;5808.361080281165;5695.401982791788;5710.857228569277;5719.914201286984;5786.884262314772;5826.184334193216;5406.107992181324;5410.269613602152;5419.458054194549;5435.510804027656;5462.126059830408;5855.502282305966;5861.443162899518;5881.852953676193;5882.088276317178;5887;5560.8331750046755;5598.048331783607;5637.520810731052;5652.123920792284;5693.572982026365;5642.6332288401945;5668.773249069991;5681.0910254359105;5684.658920464785;5689.2319536862;5602.1036634138345;5610.579206559524;5629.746661400247;5631.971840140773;5658.603897727202;5582.051051321285;5667.206591077253;5710.086386178192;5738.885222295614;5761.683107429131;4373.584509471595;4394.648428125743;4400.515943246241;4403.317485789644;4438.866833994985;3935.035916200492;3957.8021098944937;3982.4424580558743;3987.2224916141654;4000.3199456092534;5410.269613602113;5421.241026256327;5434.483724046209;5473.151661492475;5538.086215774432;5735;5744.253247077853;5783.421657834183;5790.449907258888;5795.565332599987;5360.410354860962;5376.354837419534;5391.919121213185;5420.512153906088;5444.40111587725;4923.704577725276;4925.679830810986;4938.975305123451;4946.554810067111;4964.35463389757;5957.970210148921;5964.433378829004;5989.790357337472;5995.430434108753;6006.309274433409;5692;5733.8566535836935;5772.307323121085;5802.622829516068;5912.73392697321;5904.350521442552;5914.911276330945;5937.910931335947;5962.493188079062;5971.28344598651;5672.688002159755;5675.545956323402;5685.573581981451;5689.2319536862;5693.9430605693415;5598.964191624563;5731.942680573141;5794.304683438015;5865.794697185578;5890.175375447355;5613.971930140323;5617.775288988395;5619.044762390486;5626.85932851682;5661.518770904519;5537.307836520435;5604.859878502907;5641.830745077655;5663.122216056518;5668.291463567054;5447.101228297287;5512.80705175317;5585.301837270341;5602.803901863415;5621.887562248814;5510.476504732044;5576.414476479911;5581;5627.184058311604;5644.717764111696;5493.615446918678;5528.861778455571;5530.281063461724;5548.861278468071;5595.272614560081 09:40:31 INFO - PROCESS | 27319 | |1;ext.html;542.2489851555596;550.9696966666834;572.0275531596283;573.1746285349097;587.195542107313;38674.22651546967;39419.60580394195;39587.60412395875;39629.80185099074;39812.203755924864;41071.79464102683;41101;41389.79305103478;41475.17049659012;41582.16835663285;8789;8942.687005954793;8970.551472426385;8975.281977441822;9009.594568244413;8725.38922275441;8849.115088491131;8852.203301702846;9048.909510904872;9117.772055698628;9032.8645070324;9061.048589898064;9087.31845111616;9100.408473449237;9105.95447022764;8920.8661870072;9009.95495022524;9026;9039.548022598843;9046.140616641409;8890.288776897833;8982.685606003824;8997.505137217451;9017;9046.86429703555;41349;42063.57936420627;42110.78944605257;42271.7886410566;42280.365794513105;3296.917577060569;3601.351756683796;3738.140227747633;3799.0692280391204;3991.780452075164;27946.161615151577;27975.300617484725;28089.438211235865;28349.57475637867;28796.568051479244;4124.670026397881;4144.357624568197;4192.601702838226;4203.7687927164;4308.719933204363;361390;363617;367486;367974.9999999973;368299;44003.33994990078;44309.778451108185;44648.55351446444;60725.39274607286;61103.08345374823;43211;43362;43457.565424345354;43471.78264108659;43558.564414356086;15499.380024799108;15771.68456630861;15821.525354239391;15868.44460443879;15918.522444326689;12670.746585068247;12783.872161278454;12822.80765788514;12834.486620535261;12837.807432888514;14851;14951.700965980619;15042.646161845267;15088.094714317176;15186.088834669956;5791.189233507313;5904.291485021825;6005.609635373687;6015.127806468037;6021.157038014681;935.222932775903;985.7190700650281;1054.915606751458;1108.41808050774;1158.478684591937;30105.397892042038;30173.245668858017;30288.848555757522;30795.23011924675;30798.384032319675;1165.621173118723;1210.0924306769923;1242.0374209987333;1244.5146392906775;1246.376811594203;15659.060456372654;16054.43809466663;16143.838561614233;16309.347626095061;16464.094474803762;516383;521690;522528;527190;530234;56381.71809140928;56702;56906.14640780391;56967.715161424756;57258.427415726146;1479.2603698150924;1490.3442485306648;1492.4925525321225;1496.124767011289;1508.4494159631656;29737.702622973495;30797.384052319278;32499.512507312884;32958.83520582478;33561.993140205836;49663;50437.24344134767;50491.49508504868;50522.74738626283;50834.49165508297;328518;328913;332344;333259;333440;34803.477947830295;35340.823295883354;35770.28459430745;35795.463068053476;36238.275234494635;46322.30516542322;46354.76822615865;46915.76542117268;47003.52996470128;47142.057158857315;39417.40873886837;39443.80278098591;39540.80229598834;40104.997375065854;40407.79796101001;39514.40728389019;40131.19737605173;40765.38851917164;41690.374644380965;43480.565194348914;37594.43608345932;37623.81188094042;37873.62126378701;37952.62047379491;38103;33879.66120338765;34154.658453416145;34372.48441273433;34815.65184348124;35051;26148;26829.463410732067;27217.59173612437;27301.317467063483;27522.707725349723 09:40:31 INFO - PROCESS | 27319 | |2;jquery.html;379.57298039705347;381.3555091894696;381.7881076002817;383.6700437623644;384.3581219363662;15100.848991510082;16267.34930602776;17520.123993800324;18396.17217225225;18443.446696599105;15883;15962.281697323648;16465.094419806905;16473.752893706605;16491.257893394795;5667.886642267152;5836.212111364954;5853.678047707395;5871.500922421599;5872.735726892279;5234.110201265794;5444.891102177954;5449.237106805031;5468.589855760814;5748.913766293509;5401.5948803839865;5456.0997435423305;5700.287464066992;6020.307664618559;6020.819375418744;5724.713764311768;5751.654900705971;6240.063990401431;6252.593581417216;6291.5281353898645;5500.944990550083;5511.641743286674;5522.502974732293;5524.613277070606;5527.198556209368;13023.609291721263;13633.863661363457;20393.4901627459;21480.24819131323;21515.493915425926;2195.3413975807352;2281.0761641535037;2286.4055345610095;2290.8510946788406;2394.449276666359;9576.425414475154;10358.808736995266;10675.359478431319;11071.778564428667;13844.10013349139;1878.4083013850593;1929.2668785861424;1936.471374226577;1992.3823614679495;2033.0647901964967;61642.38357616411;62534;62890;63507.6824615874;64875.35124648787;10985.516955210944;11063.004329610374;11063.502142403611;11069.778604427866;11083.72290692739;14597.635059123606;14623.707525849637;14644.853551464563;14668.633284167769;14728.779068313985;8288.129746376664;8308.501489910625;8357.331413486907;8382.16178382157;8472.957635211784;7936.960315198503;7971.8405631887035;8033.236842499953;8048.275655191061;8065.153158918347;4634.907301853944;4726.409198850144;4782.641301902371;4862.294967229803;4904.950950490521;1952;1958.1873522488008;1975.3777560068531;2018.9697154542694;2021.2117274263048;965.165132160676;991.2367477042637;1035.7100011996654;1044.1751016696724;1067.4289255248405;14416.711665766627;17647.205875735366;17783.19975601101;17908.731369029476;18355.357562485515;980.612658000086;988.7033889833021;1020.1685626214611;1035.295998720862;1086.6033897627467;9434.95282523583;9583.377080489747;9660.37207581505;9687.17658998993;9766.951165244129;527190;529883;584976;623574;635084;48125;57163.428365716645;67233.66383168053;67324.6633766828;76811.61594191994;1178.7524619829933;1207.5471698113208;1215.805471124616;1218.2629509147193;1225.4546726706708;15527.146006969726;15561.299741511886;15643.826712996342;15732.292046858147;15917.363305467652;31376;32607.34785304328;32726;32929;33218.16954576155;41835;43054;43702.34446483369;44715.776421117684;44963;7582.090149182133;7968.243016913384;8033.156518565466;8070.677172913018;8136.796580085545;11267.549298027987;11512.30926144434;11748.177627565989;11866.05071594253;12281.017518598312;3243.7242834359345;7858.253465920729;9802.950985245028;10162.695119146438;10531.526081326512;10312.587496500357;10703.946480267548;10751.354918704903;10803.189760768131;10917.23579349438;9904.356216845881;10442.582296708046;10514.842277365999;10745.462726863469;11008;7796.064472263365;7907.367410607253;7926.286634202949;8023.117457079833;8027.39794515402;9167.816643666956;10097.89902100999;10221.182305415698;10538.209634277306;10545.99813017762 09:40:31 INFO - PROCESS | 27319 | |3;mootools.html;1519.5517322389894;1555.2379334126279;1741;1837.6416598763246;1868.5608881912756;7917.6041197940085;7945.483543569664;8175.46859454135;8177.50934943903;8217.794555136124;8265.545395003272;8292.295154911839;8322.75031749047;8407.621657025433;8409.285210757092;2363.763623637635;2415.1426243683504;2415.7825795678386;2420.624803155505;2426.6360045993065;2430.44099857033;2450.252672934754;2472.81453890958;2473.5671257529953;2485.6147297168877;2343.847649902751;2411.8432301900407;2424.830261881669;2453.877306134695;2478.095495144268;2328.6856274402994;2334.9649755253686;2338.9298321050396;2355.034435881289;2368.8223383246327;2449.7182823975295;2466.334089795745;2467.0748469324003;2474.39377352548;2478.962815557768;8131.7560473185895;8173.67305307787;8174.550399728013;8191.098979112293;8203.097659257477;5003;5059.494050594948;5106.770195341219;5138.409082955471;5163.0190263849745;3140.2934339773597;3162;3179.173414912116;3184.7770656054086;3193.4730769423018;1085.5060947268973;1093.792179485903;1101.6254473478975;1103.2167161315476;1157.9131565132564;11268;11373.601923932705;11391.316521008683;11392.94303528477;11418.885811141865;4404.1411924674385;4467.106578684276;4490.3040028795585;4540.228161212602;4555.157295900269;9350.251979841596;9381.390209636353;9390.436573805593;9393.248540116774;9415.011423800403;5031.547160755476;5052.242163675478;5096.210087436453;5103.18349064148;5125.718085505333;4622.329762184464;4631.8842028948875;4697.647676424282;4699.577038066522;4713.34013238147;3724.050367156358;3736.308782875177;3773.1510410157493;3781.6596506314563;3806.847726090981;1089.9346039237512;1132.6149109302876;1154.3650991954391;1157.4212893553224;1163.4531770068054;1052.5737076484036;1059.5549869055014;1128.7347473343757;1202.9699257518457;1241.205628397826;7554.773356799305;7566.470347075762;7567.848643027109;7587.051618547682;7607.391408687292;1150.9539618415338;1153.8211577205548;1161.703765539782;1179.2983175010854;1181.291225264834;6091.208142941389;6162.26052873658;6169.290531588835;6182.103594978703;6199.101130336076;9754.365966212174;10060.24548158891;10088.646897358558;10231.744206394751;10234.283600147919;9213.216876565433;9932.25508086882;9947.452890091114;10032.695749552511;10048.79902401948;7800.765977020698;7833.843323133506;7837.412194085352;7868.921310786819;7890.605469726491;7842.647080881488;7933.444658874054;7951.204879512003;7953;7987.321077708228;8349.290310323691;8458.788530286793;8942.329325300498;8972.057933916845;9006.954965225394;8511.404201705822;8595.18345757152;8644.178803013703;8730.083341249077;8828;7239.058922340062;7324.157721862054;7344.934984427335;7368.705251789868;7392.297731715478;7671.2712292332135;7683.9231607683205;7729.381649468141;7738.264864837832;7751.0698716152165;7495.213002634645;7508.0239568855695;7592.962035189788;7632.236776322324;7740.032495938008;6673.666316684049;6750.493712971449;7153.856922861618;7185.784426467214;7330.890036649561;7393.223711510429;7406.814829629302;7407.037085178892;7435.739749108755;7453.217412171861;6825.590464572141;6854.280300568368;6856.6228857413325;6869.416099631588;6893.20728116253;6887.104676392037;6891.827704307432;6901.930980690129;6971.372576468142;7078.893816592652 09:40:31 INFO - PROCESS | 27319 | |4;prototype.html;362.9764065335753;369.04233514031097;371.2963933346307;373.482726423903;376.69487714950884;6303.275123360815;6325.304216536178;6410.070539771737;6498.122753428286;6512.869742605145;6350.491555835454;6363.045543168516;6388.936110638892;6407.423331900128;6414.839629009265;4921.532454416824;4925.187344088239;4927.063857867011;4950.727709975949;4960.999999999991;4886.144924638192;4887.975560122194;4897.118518666639;4909.239067944474;4915.877103072416;4510.842120525782;4804.423469183703;4821.566059054684;4828.661993660445;4851.442084160331;4804.015176888729;4804.423469183703;4855.927161092604;4868.902621947541;4873.975630121862;4248.129133527619;4287.764172970486;4293.119910418388;4293.141371725667;4300;5894.115882617642;5990.64056156632;6016.909746353808;6022.78920237794;6030.306514750816;5699.74351154199;5736.397678243766;5739.454751798614;5746.051901436258;5752.511036561856;6712.160979877515;6781.813182693009;6809.250982391933;6809.761658341934;6814.829629259259;4771.427428708577;4787.066522028206;4789.928151077702;4807.759612019385;4812.302216178635;6873.965630171867;7041.788746337618;7049.118860142482;7056.470764692617;7083.6458177090935;4886.633502487257;4892.730899800546;4893;4906.754662266872;4913.828016019422;4947.2579113133315;4975.203967365204;4983.750812459362;4985.875353116201;4986;5599.1041433370465;5699.145128230715;5725.914111288334;5732.426757324234;5733.741981610838;6756.76351327711;6783.457323414115;6784.287649796799;6816.386525212754;6822.590644561342;1708.8465285931945;1732.9169269206745;1750.6323672028761;1756.4291605228352;1760.64787042592;1183.6271574454192;1190.6428071578664;1193.6061099837034;1201.483362154282;1205.9457324420246;6441.420272175433;6462.903056454157;6467.676616169173;6468.935310646833;6475.125858009108;6927.411170050501;6959.338862808025;6981.860362792716;6989.755358562527;7004.194517630436;6348;6369.904451433232;6420.518461115342;6426.550141490145;6458.483321334282;3762.4761971401626;3775.6413140751583;3780.9621903781162;3798.677112445473;3804.4483549885667;7139.96430017857;7148.678309476086;7180.5691658500655;7184.1019872515935;7239.131304243525;7039.120109986252;7149.678264478111;7185.353318201387;7185.389241914496;7200.71197152108;7217.8917316240295;7219.602921839244;7223.711051557984;7224;7244.49288549807;6923.792286231421;6947.965260173667;6954.130733658293;6971.163460384583;6986.580805151707;6721.260661327152;6837.418819400406;6841.521093523405;6866.6566671667215;6877.759278425431;7163.140423149255;7184.1019872515935;7199.06412166415;7208.918662200712;7230.734621441122;4903.436104847988;4909.4845041270155;4910.116179087799;4921.458639549575;4924.310596516419;6824.2152152503095;6847.109875716125;6956.373926346653;6969.337912898267;6977.406920411822;6704.932950670626;6847;6860.6912688930115;6901.482388820758;6907.930920690729;6823.532940417834;6844.486663500158;6844.486663500357;6860.897086543606;6883.242843287335;6587.308332625005;6695.866082678416;6739.427148692416;6765.594064356154;6766.4248538874745;6886.414654754402;6891.517593768589;6898.413634841095;6909.998050282781;6915.51591388598;6686.231083425469;6698.66506674674;6713.160854893138;6716.798496045127;6731.091302674076 09:40:31 INFO - PROCESS | 27319 | |5;yui.html;712.4229374206892;716.7419728897596;787.5314188058103;807.5639154856378;872.616048938467;14604.926975365122;16129.838701612995;16267.267972941216;16281.267342969566;16310.266038028287;13726.93136534316;13882.86117138831;13922.582322530316;13935.721285574282;13965.650858728537;875.9211670949613;879.7140929198005;905.5698543191997;906.5875026862761;911.5578944212052;865.1651156633847;887.5739644970419;892.1301730812471;893.6648756716231;898.7124120281512;931.7391130483476;934.397313732642;941.9576119074624;942.5993952570165;949.3639261694647;915.839728047589;929.1219797291562;939.3659279985982;941.6845356805478;941.6939494664227;681.5093132944272;682.0519477925687;682.8019874236466;690.8169335126196;701.291695387657;15131.773023404658;15156.772648410282;15187.240637968058;15246.23768811555;15256.618584535365;13599.32003399836;13788.655283617889;13808.861911380958;13851.238181899991;13860.861391386057;10907.18196135293;11317.603883864025;11435.828462573068;11507.539698412054;11736.471858766376;3843.8846834595006;3856.382978723391;3865.729398942075;3868.516435445569;3877;51085.48914510845;51155.74422127903;51294.48705512972;51470.22794658083;51515.74242128765;10717.196210284259;10735.624253151102;10746.355218686904;10859.18556108295;11041.282316649393;18862.71705924412;18934.24263029491;18935.147918343708;18995.905020475086;19000.52498687544;7746.49647772893;7805.297523222937;7844.882326765103;7879.527228366202;8137.471064380797;4439.289713645801;4479.910401792011;4491.910161796811;4516.412866327357;4549.067441174551;3126.7185953264316;3130.4208721386617;3156.210947263184;3213.4697774867354;3233.0785726067957;590.4892268187987;612.0146564031905;612.063542779548;613.6440864298785;618.4619381138349;611.3366996808423;637.6397335505425;643.234550884446;644.1432894250636;654.7021105397033;7839.960800195962;7974.362051035903;8154.8776768348525;8164.918350816416;8236.299914507214;554.9778008879599;560.3975726094465;601.8525461262019;732.0958616109101;760.5398733766102;4262.531121576624;4382.737035777864;4511.120331535385;4668.97665511677;4704.223803072455;58711.41288587145;59533;59768.40231597715;60161.097583536284;60168;55418.72290638521;55489.445105548424;55513.722431387585;56725;56962;48676.02647947021;49091.263631045564;49798.50201498011;49856.252156217684;49991.750041250285;9734.41593504392;9840.507974601098;10035.648752293635;10040.046195611405;10119.443430611387;35070.649293506736;35203.82398088096;36327.45508817317;36395.09012274714;36830.26339473142;45448.31827522524;47786.522134778206;47789;47909;48118;11916.642500724993;12172;12200.084993625691;12308.938455307667;12446.502139914302;13277;13429.059965802691;13548.525801596898;13901.860981390057;14021.158730476203;12557.372131393577;12747.745045099231;12797.8080328797;12824.29466379358;12920.547780827626;12400.81398779001;12417.875821241672;12567.057470689551;12583.811242831182;12707.046971476992;12496.25022498653;12736.172148810298;12770.553030643883;12943.223406595635;12997.935010324887;9796.706098817047;10030.348027378197;10156.593736250466;10454.843177352193;10563.524641391003;9993.850092248476;10644.946775266075;10799.784004319712;10818.350898946088;10986.615468458565 09:40:31 INFO - PROCESS | 27319 | __end_tp_report 09:40:31 INFO - PROCESS | 27319 | __start_cc_report 09:40:31 INFO - PROCESS | 27319 | _x_x_mozilla_cycle_collect,301 09:40:31 INFO - PROCESS | 27319 | __end_cc_report 09:40:31 INFO - PROCESS | 27319 | __startTimestamp1456767631365__endTimestamp 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | ------- Summary: start ------- 09:40:31 INFO - PROCESS | 27319 | Number of tests: 6 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | [#0] dojo.html Cycles:165 Average:5347.63 Median:5548.86 stddev:568.09 (10.2%) stddev-sans-first:547.25 09:40:31 INFO - PROCESS | 27319 | Values: 3326.2 3336.9 3351.5 3366.0 3376.7 5345.7 5363.8 5366.0 5377.7 5390.8 5353.1 5356.2 5374.2 5387.4 5399.2 4929.8 4947.9 4997.9 4999.9 5093.3 4792.8 4871.5 4951.1 4956.2 4956.4 4948.9 4967.4 4993.6 5009.6 5014.3 4941.6 4958.0 4965.0 4966.6 5003.8 4810.3 4840.6 4890.3 4933.0 4936.0 5118.5 5149.2 5149.5 5157.6 5175.2 5760.0 5776.5 5797.3 5807.8 5808.4 5695.4 5710.9 5719.9 5786.9 5826.2 5406.1 5410.3 5419.5 5435.5 5462.1 5855.5 5861.4 5881.9 5882.1 5887.0 5560.8 5598.0 5637.5 5652.1 5693.6 5642.6 5668.8 5681.1 5684.7 5689.2 5602.1 5610.6 5629.7 5632.0 5658.6 5582.1 5667.2 5710.1 5738.9 5761.7 4373.6 4394.6 4400.5 4403.3 4438.9 3935.0 3957.8 3982.4 3987.2 4000.3 5410.3 5421.2 5434.5 5473.2 5538.1 5735.0 5744.3 5783.4 5790.4 5795.6 5360.4 5376.4 5391.9 5420.5 5444.4 4923.7 4925.7 4939.0 4946.6 4964.4 5958.0 5964.4 5989.8 5995.4 6006.3 5692.0 5733.9 5772.3 5802.6 5912.7 5904.4 5914.9 5937.9 5962.5 5971.3 5672.7 5675.5 5685.6 5689.2 5693.9 5599.0 5731.9 5794.3 5865.8 5890.2 5614.0 5617.8 5619.0 5626.9 5661.5 5537.3 5604.9 5641.8 5663.1 5668.3 5447.1 5512.8 5585.3 5602.8 5621.9 5510.5 5576.4 5581.0 5627.2 5644.7 5493.6 5528.9 5530.3 5548.9 5595.3 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | [#1] ext.html Cycles:180 Average:56136.69 Median:21901.99 stddev:110081.20 (502.6%) stddev-sans-first:110310.86 09:40:31 INFO - PROCESS | 27319 | Values: 542.2 551.0 572.0 573.2 587.2 38674.2 39419.6 39587.6 39629.8 39812.2 41071.8 41101.0 41389.8 41475.2 41582.2 8789.0 8942.7 8970.6 8975.3 9009.6 8725.4 8849.1 8852.2 9048.9 9117.8 9032.9 9061.0 9087.3 9100.4 9106.0 8920.9 9010.0 9026.0 9039.5 9046.1 8890.3 8982.7 8997.5 9017.0 9046.9 41349.0 42063.6 42110.8 42271.8 42280.4 3296.9 3601.4 3738.1 3799.1 3991.8 27946.2 27975.3 28089.4 28349.6 28796.6 4124.7 4144.4 4192.6 4203.8 4308.7 361390.0 363617.0 367486.0 367975.0 368299.0 44003.3 44309.8 44648.6 60725.4 61103.1 43211.0 43362.0 43457.6 43471.8 43558.6 15499.4 15771.7 15821.5 15868.4 15918.5 12670.7 12783.9 12822.8 12834.5 12837.8 14851.0 14951.7 15042.6 15088.1 15186.1 5791.2 5904.3 6005.6 6015.1 6021.2 935.2 985.7 1054.9 1108.4 1158.5 30105.4 30173.2 30288.8 30795.2 30798.4 1165.6 1210.1 1242.0 1244.5 1246.4 15659.1 16054.4 16143.8 16309.3 16464.1 516383.0 521690.0 522528.0 527190.0 530234.0 56381.7 56702.0 56906.1 56967.7 57258.4 1479.3 1490.3 1492.5 1496.1 1508.4 29737.7 30797.4 32499.5 32958.8 33562.0 49663.0 50437.2 50491.5 50522.7 50834.5 328518.0 328913.0 332344.0 333259.0 333440.0 34803.5 35340.8 35770.3 35795.5 36238.3 46322.3 46354.8 46915.8 47003.5 47142.1 39417.4 39443.8 39540.8 40105.0 40407.8 39514.4 40131.2 40765.4 41690.4 43480.6 37594.4 37623.8 37873.6 37952.6 38103.0 33879.7 34154.7 34372.5 34815.7 35051.0 26148.0 26829.5 27217.6 27301.3 27522.7 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | [#2] jquery.html Cycles:180 Average:28900.44 Median:16798.87 stddev:94928.50 (565.1%) stddev-sans-first:95170.64 09:40:31 INFO - PROCESS | 27319 | Values: 379.6 381.4 381.8 383.7 384.4 15100.8 16267.3 17520.1 18396.2 18443.4 15883.0 15962.3 16465.1 16473.8 16491.3 5667.9 5836.2 5853.7 5871.5 5872.7 5234.1 5444.9 5449.2 5468.6 5748.9 5401.6 5456.1 5700.3 6020.3 6020.8 5724.7 5751.7 6240.1 6252.6 6291.5 5500.9 5511.6 5522.5 5524.6 5527.2 13023.6 13633.9 20393.5 21480.2 21515.5 2195.3 2281.1 2286.4 2290.9 2394.4 9576.4 10358.8 10675.4 11071.8 13844.1 1878.4 1929.3 1936.5 1992.4 2033.1 61642.4 62534.0 62890.0 63507.7 64875.4 10985.5 11063.0 11063.5 11069.8 11083.7 14597.6 14623.7 14644.9 14668.6 14728.8 8288.1 8308.5 8357.3 8382.2 8473.0 7937.0 7971.8 8033.2 8048.3 8065.2 4634.9 4726.4 4782.6 4862.3 4905.0 1952.0 1958.2 1975.4 2019.0 2021.2 965.2 991.2 1035.7 1044.2 1067.4 14416.7 17647.2 17783.2 17908.7 18355.4 980.6 988.7 1020.2 1035.3 1086.6 9435.0 9583.4 9660.4 9687.2 9767.0 527190.0 529883.0 584976.0 623574.0 635084.0 48125.0 57163.4 67233.7 67324.7 76811.6 1178.8 1207.5 1215.8 1218.3 1225.5 15527.1 15561.3 15643.8 15732.3 15917.4 31376.0 32607.3 32726.0 32929.0 33218.2 41835.0 43054.0 43702.3 44715.8 44963.0 7582.1 7968.2 8033.2 8070.7 8136.8 11267.5 11512.3 11748.2 11866.1 12281.0 3243.7 7858.3 9803.0 10162.7 10531.5 10312.6 10703.9 10751.4 10803.2 10917.2 9904.4 10442.6 10514.8 10745.5 11008.0 7796.1 7907.4 7926.3 8023.1 8027.4 9167.8 10097.9 10221.2 10538.2 10546.0 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | [#3] mootools.html Cycles:180 Average:5769.83 Median:5627.11 stddev:2973.91 (52.8%) stddev-sans-first:2965.10 09:40:31 INFO - PROCESS | 27319 | Values: 1519.6 1555.2 1741.0 1837.6 1868.6 7917.6 7945.5 8175.5 8177.5 8217.8 8265.5 8292.3 8322.8 8407.6 8409.3 2363.8 2415.1 2415.8 2420.6 2426.6 2430.4 2450.3 2472.8 2473.6 2485.6 2343.8 2411.8 2424.8 2453.9 2478.1 2328.7 2335.0 2338.9 2355.0 2368.8 2449.7 2466.3 2467.1 2474.4 2479.0 8131.8 8173.7 8174.6 8191.1 8203.1 5003.0 5059.5 5106.8 5138.4 5163.0 3140.3 3162.0 3179.2 3184.8 3193.5 1085.5 1093.8 1101.6 1103.2 1157.9 11268.0 11373.6 11391.3 11392.9 11418.9 4404.1 4467.1 4490.3 4540.2 4555.2 9350.3 9381.4 9390.4 9393.2 9415.0 5031.5 5052.2 5096.2 5103.2 5125.7 4622.3 4631.9 4697.6 4699.6 4713.3 3724.1 3736.3 3773.2 3781.7 3806.8 1089.9 1132.6 1154.4 1157.4 1163.5 1052.6 1059.6 1128.7 1203.0 1241.2 7554.8 7566.5 7567.8 7587.1 7607.4 1151.0 1153.8 1161.7 1179.3 1181.3 6091.2 6162.3 6169.3 6182.1 6199.1 9754.4 10060.2 10088.6 10231.7 10234.3 9213.2 9932.3 9947.5 10032.7 10048.8 7800.8 7833.8 7837.4 7868.9 7890.6 7842.6 7933.4 7951.2 7953.0 7987.3 8349.3 8458.8 8942.3 8972.1 9007.0 8511.4 8595.2 8644.2 8730.1 8828.0 7239.1 7324.2 7344.9 7368.7 7392.3 7671.3 7683.9 7729.4 7738.3 7751.1 7495.2 7508.0 7593.0 7632.2 7740.0 6673.7 6750.5 7153.9 7185.8 7330.9 7393.2 7406.8 7407.0 7435.7 7453.2 6825.6 6854.3 6856.6 6869.4 6893.2 6887.1 6891.8 6901.9 6971.4 7078.9 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | [#4] prototype.html Cycles:180 Average:5681.34 Median:6423.53 stddev:1693.89 (26.4%) stddev-sans-first:1650.93 09:40:31 INFO - PROCESS | 27319 | Values: 363.0 369.0 371.3 373.5 376.7 6303.3 6325.3 6410.1 6498.1 6512.9 6350.5 6363.0 6388.9 6407.4 6414.8 4921.5 4925.2 4927.1 4950.7 4961.0 4886.1 4888.0 4897.1 4909.2 4915.9 4510.8 4804.4 4821.6 4828.7 4851.4 4804.0 4804.4 4855.9 4868.9 4874.0 4248.1 4287.8 4293.1 4293.1 4300.0 5894.1 5990.6 6016.9 6022.8 6030.3 5699.7 5736.4 5739.5 5746.1 5752.5 6712.2 6781.8 6809.3 6809.8 6814.8 4771.4 4787.1 4789.9 4807.8 4812.3 6874.0 7041.8 7049.1 7056.5 7083.6 4886.6 4892.7 4893.0 4906.8 4913.8 4947.3 4975.2 4983.8 4985.9 4986.0 5599.1 5699.1 5725.9 5732.4 5733.7 6756.8 6783.5 6784.3 6816.4 6822.6 1708.8 1732.9 1750.6 1756.4 1760.6 1183.6 1190.6 1193.6 1201.5 1205.9 6441.4 6462.9 6467.7 6468.9 6475.1 6927.4 6959.3 6981.9 6989.8 7004.2 6348.0 6369.9 6420.5 6426.6 6458.5 3762.5 3775.6 3781.0 3798.7 3804.4 7140.0 7148.7 7180.6 7184.1 7239.1 7039.1 7149.7 7185.4 7185.4 7200.7 7217.9 7219.6 7223.7 7224.0 7244.5 6923.8 6948.0 6954.1 6971.2 6986.6 6721.3 6837.4 6841.5 6866.7 6877.8 7163.1 7184.1 7199.1 7208.9 7230.7 4903.4 4909.5 4910.1 4921.5 4924.3 6824.2 6847.1 6956.4 6969.3 6977.4 6704.9 6847.0 6860.7 6901.5 6907.9 6823.5 6844.5 6844.5 6860.9 6883.2 6587.3 6695.9 6739.4 6765.6 6766.4 6886.4 6891.5 6898.4 6910.0 6915.5 6686.2 6698.7 6713.2 6716.8 6731.1 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - PROCESS | 27319 | [#5] yui.html Cycles:180 Average:14820.26 Median:4501.52 stddev:16898.87 (375.4%) stddev-sans-first:16913.06 09:40:31 INFO - PROCESS | 27319 | Values: 712.4 716.7 787.5 807.6 872.6 14604.9 16129.8 16267.3 16281.3 16310.3 13726.9 13882.9 13922.6 13935.7 13965.7 875.9 879.7 905.6 906.6 911.6 865.2 887.6 892.1 893.7 898.7 931.7 934.4 942.0 942.6 949.4 915.8 929.1 939.4 941.7 941.7 681.5 682.1 682.8 690.8 701.3 15131.8 15156.8 15187.2 15246.2 15256.6 13599.3 13788.7 13808.9 13851.2 13860.9 10907.2 11317.6 11435.8 11507.5 11736.5 3843.9 3856.4 3865.7 3868.5 3877.0 51085.5 51155.7 51294.5 51470.2 51515.7 10717.2 10735.6 10746.4 10859.2 11041.3 18862.7 18934.2 18935.1 18995.9 19000.5 7746.5 7805.3 7844.9 7879.5 8137.5 4439.3 4479.9 4491.9 4516.4 4549.1 3126.7 3130.4 3156.2 3213.5 3233.1 590.5 612.0 612.1 613.6 618.5 611.3 637.6 643.2 644.1 654.7 7840.0 7974.4 8154.9 8164.9 8236.3 555.0 560.4 601.9 732.1 760.5 4262.5 4382.7 4511.1 4669.0 4704.2 58711.4 59533.0 59768.4 60161.1 60168.0 55418.7 55489.4 55513.7 56725.0 56962.0 48676.0 49091.3 49798.5 49856.3 49991.8 9734.4 9840.5 10035.6 10040.0 10119.4 35070.6 35203.8 36327.5 36395.1 36830.3 45448.3 47786.5 47789.0 47909.0 48118.0 11916.6 12172.0 12200.1 12308.9 12446.5 13277.0 13429.1 13548.5 13901.9 14021.2 12557.4 12747.7 12797.8 12824.3 12920.5 12400.8 12417.9 12567.1 12583.8 12707.0 12496.3 12736.2 12770.6 12943.2 12997.9 9796.7 10030.3 10156.6 10454.8 10563.5 9993.9 10644.9 10799.8 10818.4 10986.6 09:40:31 INFO - PROCESS | 27319 | -------- Summary: end -------- 09:40:31 INFO - PROCESS | 27319 | 09:40:31 INFO - TEST-INFO | 27319: exit 0 09:40:32 INFO - TEST-OK | dromaeo_css | took 1108338ms 09:40:32 INFO - TEST-START | kraken 09:40:32 INFO - operating with platform_type : linux_ 09:40:32 INFO - Initialising browser for kraken test... 09:40:32 INFO - TEST-INFO | started process 27564 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6gOyVF/profile http://localhost:38071/getInfo.html) 09:40:35 INFO - PROCESS | 27564 | 09:40:35 INFO - PROCESS | 27564 | (process:27642): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:40:36 INFO - PROCESS | 27564 | __metrics Screen width/height:1600/1200 09:40:36 INFO - PROCESS | 27564 | colorDepth:24 09:40:36 INFO - PROCESS | 27564 | Browser inner width/height: 1024/697 09:40:36 INFO - PROCESS | 27564 | __metrics 09:40:37 INFO - TEST-INFO | 27564: exit 0 09:40:37 INFO - Browser initialized. 09:40:37 INFO - Running cycle 1/1 for kraken test... 09:40:37 INFO - TEST-INFO | started process 27691 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp6gOyVF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:40:38 INFO - PROCESS | 27691 | 09:40:38 INFO - PROCESS | 27691 | (process:27754): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:40:48 INFO - PROCESS | 27691 | RSS: Main: 140935168 09:40:48 INFO - PROCESS | 27691 | 09:40:51 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38071/tests/kraken/driver.html?testName=audio-beat-detection) 09:40:52 INFO - PROCESS | 27691 | RSS: Main: 145645568 09:40:52 INFO - PROCESS | 27691 | 09:40:54 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38071/tests/kraken/driver.html?testName=audio-dft) 09:40:55 INFO - PROCESS | 27691 | RSS: Main: 144797696 09:40:55 INFO - PROCESS | 27691 | 09:40:58 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38071/tests/kraken/driver.html?testName=audio-fft) 09:40:58 INFO - PROCESS | 27691 | RSS: Main: 146554880 09:40:58 INFO - PROCESS | 27691 | 09:41:00 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38071/tests/kraken/driver.html?testName=audio-oscillator) 09:41:01 INFO - PROCESS | 27691 | RSS: Main: 146644992 09:41:01 INFO - PROCESS | 27691 | 09:41:03 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38071/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:41:04 INFO - PROCESS | 27691 | RSS: Main: 146587648 09:41:04 INFO - PROCESS | 27691 | 09:41:07 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38071/tests/kraken/driver.html?testName=imaging-darkroom) 09:41:07 INFO - PROCESS | 27691 | RSS: Main: 146980864 09:41:07 INFO - PROCESS | 27691 | 09:41:11 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38071/tests/kraken/driver.html?testName=imaging-desaturate) 09:41:11 INFO - PROCESS | 27691 | RSS: Main: 145924096 09:41:11 INFO - PROCESS | 27691 | 09:41:14 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38071/tests/kraken/driver.html?testName=json-parse-financial) 09:41:15 INFO - PROCESS | 27691 | RSS: Main: 145444864 09:41:15 INFO - PROCESS | 27691 | 09:41:16 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38071/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:41:17 INFO - PROCESS | 27691 | RSS: Main: 146083840 09:41:17 INFO - PROCESS | 27691 | 09:41:18 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38071/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:41:19 INFO - PROCESS | 27691 | RSS: Main: 145932288 09:41:19 INFO - PROCESS | 27691 | 09:41:21 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38071/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:41:22 INFO - PROCESS | 27691 | RSS: Main: 146575360 09:41:22 INFO - PROCESS | 27691 | 09:41:24 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:41:25 INFO - PROCESS | 27691 | RSS: Main: 147509248 09:41:25 INFO - PROCESS | 27691 | 09:41:27 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:41:28 INFO - PROCESS | 27691 | RSS: Main: 147267584 09:41:28 INFO - PROCESS | 27691 | 09:41:29 INFO - PROCESS | 27691 | Cycle 1(1): loaded http://localhost:38071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38071/tests/kraken/driver.html?testName=ai-astar) 09:41:30 INFO - PROCESS | 27691 | RSS: Main: 147677184 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | __start_tp_report 09:41:30 INFO - PROCESS | 27691 | _x_x_mozilla_page_load 09:41:30 INFO - PROCESS | 27691 | _x_x_mozilla_page_load_details 09:41:30 INFO - PROCESS | 27691 | |i|pagename|runs| 09:41:30 INFO - PROCESS | 27691 | |0;ai-astar;111;105;111;110;111;123;111;111;111;109 09:41:30 INFO - PROCESS | 27691 | |1;audio-beat-detection;163;127;151;171;129;155;153;136;157;126 09:41:30 INFO - PROCESS | 27691 | |2;audio-dft;161;166;163;163;163;162;162;163;165;163 09:41:30 INFO - PROCESS | 27691 | |3;audio-fft;73;74;76;161;77;73;73;74;161;75 09:41:30 INFO - PROCESS | 27691 | |4;audio-oscillator;94;96;141;95;95;95;140;94;95;137 09:41:30 INFO - PROCESS | 27691 | |5;imaging-gaussian-blur;111;110;110;110;111;110;109;109;110;110 09:41:30 INFO - PROCESS | 27691 | |6;imaging-darkroom;147;146;146;147;147;147;148;146;146;147 09:41:30 INFO - PROCESS | 27691 | |7;imaging-desaturate;96;97;97;96;97;97;97;97;96;96 09:41:30 INFO - PROCESS | 27691 | |8;json-parse-financial;60;54;61;57;57;57;59;60;56;55 09:41:30 INFO - PROCESS | 27691 | |9;json-stringify-tinderbox;64;65;64;65;64;64;65;65;65;65 09:41:30 INFO - PROCESS | 27691 | |10;stanford-crypto-aes;83;80;81;89;81;86;82;82;82;81 09:41:30 INFO - PROCESS | 27691 | |11;stanford-crypto-ccm;117;127;118;120;112;123;118;112;111;111 09:41:30 INFO - PROCESS | 27691 | |12;stanford-crypto-pbkdf2;135;138;146;137;147;125;133;136;136;126 09:41:30 INFO - PROCESS | 27691 | |13;stanford-crypto-sha256-iterative;65;67;67;66;74;64;64;64;64;65 09:41:30 INFO - PROCESS | 27691 | __end_tp_report 09:41:30 INFO - PROCESS | 27691 | __start_cc_report 09:41:30 INFO - PROCESS | 27691 | _x_x_mozilla_cycle_collect,721 09:41:30 INFO - PROCESS | 27691 | __end_cc_report 09:41:30 INFO - PROCESS | 27691 | __startTimestamp1456767690036__endTimestamp 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | ------- Summary: start ------- 09:41:30 INFO - PROCESS | 27691 | Number of tests: 14 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.00 stddev:4.52 (4.1%) stddev-sans-first:4.80 09:41:30 INFO - PROCESS | 27691 | Values: 111.0 105.0 111.0 110.0 111.0 123.0 111.0 111.0 111.0 109.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#1] audio-beat-detection Cycles:10 Average:146.80 Median:154.00 stddev:16.10 (10.5%) stddev-sans-first:15.98 09:41:30 INFO - PROCESS | 27691 | Values: 163.0 127.0 151.0 171.0 129.0 155.0 153.0 136.0 157.0 126.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.45 (0.9%) stddev-sans-first:1.32 09:41:30 INFO - PROCESS | 27691 | Values: 161.0 166.0 163.0 163.0 163.0 162.0 162.0 163.0 165.0 163.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#3] audio-fft Cycles:10 Average:91.70 Median:74.00 stddev:36.55 (49.4%) stddev-sans-first:38.13 09:41:30 INFO - PROCESS | 27691 | Values: 73.0 74.0 76.0 161.0 77.0 73.0 73.0 74.0 161.0 75.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#4] audio-oscillator Cycles:10 Average:108.20 Median:95.00 stddev:21.51 (22.6%) stddev-sans-first:22.20 09:41:30 INFO - PROCESS | 27691 | Values: 94.0 96.0 141.0 95.0 95.0 95.0 140.0 94.0 95.0 137.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 09:41:30 INFO - PROCESS | 27691 | Values: 111.0 110.0 110.0 110.0 111.0 110.0 109.0 109.0 110.0 110.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:41:30 INFO - PROCESS | 27691 | Values: 147.0 146.0 146.0 147.0 147.0 147.0 148.0 146.0 146.0 147.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:97.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 09:41:30 INFO - PROCESS | 27691 | Values: 96.0 97.0 97.0 96.0 97.0 97.0 97.0 97.0 96.0 96.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:2.32 (4.0%) stddev-sans-first:2.29 09:41:30 INFO - PROCESS | 27691 | Values: 60.0 54.0 61.0 57.0 57.0 57.0 59.0 60.0 56.0 55.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 09:41:30 INFO - PROCESS | 27691 | Values: 64.0 65.0 64.0 65.0 64.0 64.0 65.0 65.0 65.0 65.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.00 stddev:2.75 (3.4%) stddev-sans-first:2.92 09:41:30 INFO - PROCESS | 27691 | Values: 83.0 80.0 81.0 89.0 81.0 86.0 82.0 82.0 82.0 81.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#11] stanford-crypto-ccm Cycles:10 Average:116.90 Median:118.00 stddev:5.47 (4.6%) stddev-sans-first:5.80 09:41:30 INFO - PROCESS | 27691 | Values: 117.0 127.0 118.0 120.0 112.0 123.0 118.0 112.0 111.0 111.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:135.90 Median:136.50 stddev:7.13 (5.2%) stddev-sans-first:7.55 09:41:30 INFO - PROCESS | 27691 | Values: 135.0 138.0 146.0 137.0 147.0 125.0 133.0 136.0 136.0 126.0 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - PROCESS | 27691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:65.50 stddev:3.06 (4.7%) stddev-sans-first:3.22 09:41:30 INFO - PROCESS | 27691 | Values: 65.0 67.0 67.0 66.0 74.0 64.0 64.0 64.0 64.0 65.0 09:41:30 INFO - PROCESS | 27691 | -------- Summary: end -------- 09:41:30 INFO - PROCESS | 27691 | 09:41:30 INFO - TEST-INFO | 27691: exit 0 09:41:30 INFO - TEST-OK | kraken | took 58869ms 09:41:30 INFO - TEST-START | v8_7 09:41:30 INFO - operating with platform_type : linux_ 09:41:30 INFO - Initialising browser for v8_7 test... 09:41:30 INFO - TEST-INFO | started process 27982 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc2oW4m/profile http://localhost:38071/getInfo.html) 09:41:34 INFO - PROCESS | 27982 | 09:41:34 INFO - PROCESS | 27982 | (process:28060): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:41:35 INFO - PROCESS | 27982 | __metrics Screen width/height:1600/1200 09:41:35 INFO - PROCESS | 27982 | colorDepth:24 09:41:35 INFO - PROCESS | 27982 | Browser inner width/height: 1024/697 09:41:35 INFO - PROCESS | 27982 | __metrics 09:41:36 INFO - TEST-INFO | 27982: exit 0 09:41:36 INFO - Browser initialized. 09:41:36 INFO - Running cycle 1/1 for v8_7 test... 09:41:36 INFO - TEST-INFO | started process 28109 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc2oW4m/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:37 INFO - PROCESS | 28109 | 09:41:37 INFO - PROCESS | 28109 | (process:28171): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:41:47 INFO - PROCESS | 28109 | RSS: Main: 140783616 09:41:47 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | Cycle 1(1): loaded http://localhost:38071/tests/v8_7/run.html (next: http://localhost:38071/tests/v8_7/run.html) 09:42:10 INFO - PROCESS | 28109 | RSS: Main: 144039936 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | __start_tp_report 09:42:10 INFO - PROCESS | 28109 | _x_x_mozilla_page_load 09:42:10 INFO - PROCESS | 28109 | _x_x_mozilla_page_load_details 09:42:10 INFO - PROCESS | 28109 | |i|pagename|runs| 09:42:10 INFO - PROCESS | 28109 | |0;Richards;167.02856188408217 09:42:10 INFO - PROCESS | 28109 | |1;DeltaBlue;170.64846416382252 09:42:10 INFO - PROCESS | 28109 | |2;Encrypt;278.31895352073474 09:42:10 INFO - PROCESS | 28109 | |3;Decrypt;5235.602094240838 09:42:10 INFO - PROCESS | 28109 | |4;RayTrace;949.667616334283 09:42:10 INFO - PROCESS | 28109 | |5;Earley;553.4034311012729 09:42:10 INFO - PROCESS | 28109 | |6;Boyer;11258.426966292134 09:42:10 INFO - PROCESS | 28109 | |7;RegExp;39076.92307692308 09:42:10 INFO - PROCESS | 28109 | |8;Splay;533.8983050847457 09:42:10 INFO - PROCESS | 28109 | |9;NavierStokes;4854.368932038835 09:42:10 INFO - PROCESS | 28109 | __end_tp_report 09:42:10 INFO - PROCESS | 28109 | __startTimestamp1456767730418__endTimestamp 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | ------- Summary: start ------- 09:42:10 INFO - PROCESS | 28109 | Number of tests: 10 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#0] Richards Cycles:1 Average:167.03 Median:167.03 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 167.0 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#1] DeltaBlue Cycles:1 Average:170.65 Median:170.65 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 170.6 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#2] Encrypt Cycles:1 Average:278.32 Median:278.32 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 278.3 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#3] Decrypt Cycles:1 Average:5235.60 Median:5235.60 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 5235.6 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#4] RayTrace Cycles:1 Average:949.67 Median:949.67 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 949.7 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#5] Earley Cycles:1 Average:553.40 Median:553.40 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 553.4 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#6] Boyer Cycles:1 Average:11258.43 Median:11258.43 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 11258.4 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#7] RegExp Cycles:1 Average:39076.92 Median:39076.92 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 39076.9 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#8] Splay Cycles:1 Average:533.90 Median:533.90 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 533.9 09:42:10 INFO - PROCESS | 28109 | 09:42:10 INFO - PROCESS | 28109 | [#9] NavierStokes Cycles:1 Average:4854.37 Median:4854.37 stddev:0.00 (0.0%) 09:42:10 INFO - PROCESS | 28109 | Values: 4854.4 09:42:10 INFO - PROCESS | 28109 | -------- Summary: end -------- 09:42:10 INFO - PROCESS | 28109 | 09:42:11 INFO - TEST-INFO | 28109: exit 0 09:42:11 INFO - TEST-OK | v8_7 | took 40357ms 09:42:11 INFO - SUITE-END | took 1207s 09:42:11 INFO - Completed test suite (00:20:07) 09:42:11 INFO - Working with test: dromaeo_css 09:42:11 INFO - Generating results file: dromaeo_css 09:42:11 INFO - Working with test: kraken 09:42:11 INFO - Generating results file: kraken 09:42:11 INFO - javascript benchmark 09:42:11 INFO - Working with test: v8_7 09:42:11 INFO - Generating results file: v8_7 09:42:11 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:42:11 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,52,43]] 09:42:11 INFO - process_Request line: dromaeo_css 7257.34 graph.html#tests=[[72,52,43]] 09:42:11 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:42:11 INFO - process_Request line: kraken 1498.10 graph.html#tests=[[232,52,43]] 09:42:11 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:42:11 INFO - process_Request line: v8_7 21899.21 graph.html#tests=[[230,52,43]] 09:42:11 INFO - RETURN: dromaeo_css: 7257.34 09:42:11 INFO - RETURN: kraken: 1498.10 09:42:11 INFO - RETURN: v8_7: 21899.21 09:42:11 INFO - javascript benchmark 09:42:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3326.168457885529, 3336.866525338988, 3351.514030465581, 3365.9663403365976, 3376.662333766623, 5345.679259244448, 5363.785448582057, 5365.973170134153, 5377.731113444327, 5390.838274851756, 5353.0899747042995, 5356.223347614603, 5374.220737992989, 5387.380451248107, 5399.217113518547, 4929.7781599828, 4947.851564453073, 4997.875053123683, 4999.925001124986, 5093.337866077423, 4792.849716068136, 4871.464138944713, 4951.059298733229, 4956.157453232959, 4956.40523137224, 4948.901021979558, 4967.403911530621, 4993.6254780891395, 5009.57418619416, 5014.272930425086, 4941.579965702919, 4958.033183529214, 4964.975175124119, 4966.627502937295, 5003.774830132635, 4810.326554282403, 4840.636952228561, 4890.290907818382, 4932.96407754372, 4936.0, 5118.539331460149, 5149.150390185615, 5149.510796474329, 5157.58739300855, 5175.171972484384, 5759.971200144015, 5776.451237132465, 5797.304323481167, 5807.796727114531, 5808.361080281165, 5695.401982791788, 5710.857228569277, 5719.914201286984, 5786.884262314772, 5826.184334193216, 5406.107992181324, 5410.269613602152, 5419.458054194549, 5435.510804027656, 5462.126059830408, 5855.502282305966, 5861.443162899518, 5881.852953676193, 5882.088276317178, 5887.0, 5560.8331750046755, 5598.048331783607, 5637.520810731052, 5652.123920792284, 5693.572982026365, 5642.6332288401945, 5668.773249069991, 5681.0910254359105, 5684.658920464785, 5689.2319536862, 5602.1036634138345, 5610.579206559524, 5629.746661400247, 5631.971840140773, 5658.603897727202, 5582.051051321285, 5667.206591077253, 5710.086386178192, 5738.885222295614, 5761.683107429131, 4373.584509471595, 4394.648428125743, 4400.515943246241, 4403.317485789644, 4438.866833994985, 3935.035916200492, 3957.8021098944937, 3982.4424580558743, 3987.2224916141654, 4000.3199456092534, 5410.269613602113, 5421.241026256327, 5434.483724046209, 5473.151661492475, 5538.086215774432, 5735.0, 5744.253247077853, 5783.421657834183, 5790.449907258888, 5795.565332599987, 5360.410354860962, 5376.354837419534, 5391.919121213185, 5420.512153906088, 5444.40111587725, 4923.704577725276, 4925.679830810986, 4938.975305123451, 4946.554810067111, 4964.35463389757, 5957.970210148921, 5964.433378829004, 5989.790357337472, 5995.430434108753, 6006.309274433409, 5692.0, 5733.8566535836935, 5772.307323121085, 5802.622829516068, 5912.73392697321, 5904.350521442552, 5914.911276330945, 5937.910931335947, 5962.493188079062, 5971.28344598651, 5672.688002159755, 5675.545956323402, 5685.573581981451, 5689.2319536862, 5693.9430605693415, 5598.964191624563, 5731.942680573141, 5794.304683438015, 5865.794697185578, 5890.175375447355, 5613.971930140323, 5617.775288988395, 5619.044762390486, 5626.85932851682, 5661.518770904519, 5537.307836520435, 5604.859878502907, 5641.830745077655, 5663.122216056518, 5668.291463567054, 5447.101228297287, 5512.80705175317, 5585.301837270341, 5602.803901863415, 5621.887562248814, 5510.476504732044, 5576.414476479911, 5581.0, 5627.184058311604, 5644.717764111696, 5493.615446918678, 5528.861778455571, 5530.281063461724, 5548.861278468071, 5595.272614560081], "unit": "score", "name": "dojo.html", "value": 5312.744356034912}, {"lowerIsBetter": false, "replicates": [542.2489851555596, 550.9696966666834, 572.0275531596283, 573.1746285349097, 587.195542107313, 38674.22651546967, 39419.60580394195, 39587.60412395875, 39629.80185099074, 39812.203755924864, 41071.79464102683, 41101.0, 41389.79305103478, 41475.17049659012, 41582.16835663285, 8789.0, 8942.687005954793, 8970.551472426385, 8975.281977441822, 9009.594568244413, 8725.38922275441, 8849.115088491131, 8852.203301702846, 9048.909510904872, 9117.772055698628, 9032.8645070324, 9061.048589898064, 9087.31845111616, 9100.408473449237, 9105.95447022764, 8920.8661870072, 9009.95495022524, 9026.0, 9039.548022598843, 9046.140616641409, 8890.288776897833, 8982.685606003824, 8997.505137217451, 9017.0, 9046.86429703555, 41349.0, 42063.57936420627, 42110.78944605257, 42271.7886410566, 42280.365794513105, 3296.917577060569, 3601.351756683796, 3738.140227747633, 3799.0692280391204, 3991.780452075164, 27946.161615151577, 27975.300617484725, 28089.438211235865, 28349.57475637867, 28796.568051479244, 4124.670026397881, 4144.357624568197, 4192.601702838226, 4203.7687927164, 4308.719933204363, 361390.0, 363617.0, 367486.0, 367974.9999999973, 368299.0, 44003.33994990078, 44309.778451108185, 44648.55351446444, 60725.39274607286, 61103.08345374823, 43211.0, 43362.0, 43457.565424345354, 43471.78264108659, 43558.564414356086, 15499.380024799108, 15771.68456630861, 15821.525354239391, 15868.44460443879, 15918.522444326689, 12670.746585068247, 12783.872161278454, 12822.80765788514, 12834.486620535261, 12837.807432888514, 14851.0, 14951.700965980619, 15042.646161845267, 15088.094714317176, 15186.088834669956, 5791.189233507313, 5904.291485021825, 6005.609635373687, 6015.127806468037, 6021.157038014681, 935.222932775903, 985.7190700650281, 1054.915606751458, 1108.41808050774, 1158.478684591937, 30105.397892042038, 30173.245668858017, 30288.848555757522, 30795.23011924675, 30798.384032319675, 1165.621173118723, 1210.0924306769923, 1242.0374209987333, 1244.5146392906775, 1246.376811594203, 15659.060456372654, 16054.43809466663, 16143.838561614233, 16309.347626095061, 16464.094474803762, 516383.0, 521690.0, 522528.0, 527190.0, 530234.0, 56381.71809140928, 56702.0, 56906.14640780391, 56967.715161424756, 57258.427415726146, 1479.2603698150924, 1490.3442485306648, 1492.4925525321225, 1496.124767011289, 1508.4494159631656, 29737.702622973495, 30797.384052319278, 32499.512507312884, 32958.83520582478, 33561.993140205836, 49663.0, 50437.24344134767, 50491.49508504868, 50522.74738626283, 50834.49165508297, 328518.0, 328913.0, 332344.0, 333259.0, 333440.0, 34803.477947830295, 35340.823295883354, 35770.28459430745, 35795.463068053476, 36238.275234494635, 46322.30516542322, 46354.76822615865, 46915.76542117268, 47003.52996470128, 47142.057158857315, 39417.40873886837, 39443.80278098591, 39540.80229598834, 40104.997375065854, 40407.79796101001, 39514.40728389019, 40131.19737605173, 40765.38851917164, 41690.374644380965, 43480.565194348914, 37594.43608345932, 37623.81188094042, 37873.62126378701, 37952.62047379491, 38103.0, 33879.66120338765, 34154.658453416145, 34372.48441273433, 34815.65184348124, 35051.0, 26148.0, 26829.463410732067, 27217.59173612437, 27301.317467063483, 27522.707725349723], "unit": "score", "name": "ext.html", "value": 19343.329865371106}, {"lowerIsBetter": false, "replicates": [379.57298039705347, 381.3555091894696, 381.7881076002817, 383.6700437623644, 384.3581219363662, 15100.848991510082, 16267.34930602776, 17520.123993800324, 18396.17217225225, 18443.446696599105, 15883.0, 15962.281697323648, 16465.094419806905, 16473.752893706605, 16491.257893394795, 5667.886642267152, 5836.212111364954, 5853.678047707395, 5871.500922421599, 5872.735726892279, 5234.110201265794, 5444.891102177954, 5449.237106805031, 5468.589855760814, 5748.913766293509, 5401.5948803839865, 5456.0997435423305, 5700.287464066992, 6020.307664618559, 6020.819375418744, 5724.713764311768, 5751.654900705971, 6240.063990401431, 6252.593581417216, 6291.5281353898645, 5500.944990550083, 5511.641743286674, 5522.502974732293, 5524.613277070606, 5527.198556209368, 13023.609291721263, 13633.863661363457, 20393.4901627459, 21480.24819131323, 21515.493915425926, 2195.3413975807352, 2281.0761641535037, 2286.4055345610095, 2290.8510946788406, 2394.449276666359, 9576.425414475154, 10358.808736995266, 10675.359478431319, 11071.778564428667, 13844.10013349139, 1878.4083013850593, 1929.2668785861424, 1936.471374226577, 1992.3823614679495, 2033.0647901964967, 61642.38357616411, 62534.0, 62890.0, 63507.6824615874, 64875.35124648787, 10985.516955210944, 11063.004329610374, 11063.502142403611, 11069.778604427866, 11083.72290692739, 14597.635059123606, 14623.707525849637, 14644.853551464563, 14668.633284167769, 14728.779068313985, 8288.129746376664, 8308.501489910625, 8357.331413486907, 8382.16178382157, 8472.957635211784, 7936.960315198503, 7971.8405631887035, 8033.236842499953, 8048.275655191061, 8065.153158918347, 4634.907301853944, 4726.409198850144, 4782.641301902371, 4862.294967229803, 4904.950950490521, 1952.0, 1958.1873522488008, 1975.3777560068531, 2018.9697154542694, 2021.2117274263048, 965.165132160676, 991.2367477042637, 1035.7100011996654, 1044.1751016696724, 1067.4289255248405, 14416.711665766627, 17647.205875735366, 17783.19975601101, 17908.731369029476, 18355.357562485515, 980.612658000086, 988.7033889833021, 1020.1685626214611, 1035.295998720862, 1086.6033897627467, 9434.95282523583, 9583.377080489747, 9660.37207581505, 9687.17658998993, 9766.951165244129, 527190.0, 529883.0, 584976.0, 623574.0, 635084.0, 48125.0, 57163.428365716645, 67233.66383168053, 67324.6633766828, 76811.61594191994, 1178.7524619829933, 1207.5471698113208, 1215.805471124616, 1218.2629509147193, 1225.4546726706708, 15527.146006969726, 15561.299741511886, 15643.826712996342, 15732.292046858147, 15917.363305467652, 31376.0, 32607.34785304328, 32726.0, 32929.0, 33218.16954576155, 41835.0, 43054.0, 43702.34446483369, 44715.776421117684, 44963.0, 7582.090149182133, 7968.243016913384, 8033.156518565466, 8070.677172913018, 8136.796580085545, 11267.549298027987, 11512.30926144434, 11748.177627565989, 11866.05071594253, 12281.017518598312, 3243.7242834359345, 7858.253465920729, 9802.950985245028, 10162.695119146438, 10531.526081326512, 10312.587496500357, 10703.946480267548, 10751.354918704903, 10803.189760768131, 10917.23579349438, 9904.356216845881, 10442.582296708046, 10514.842277365999, 10745.462726863469, 11008.0, 7796.064472263365, 7907.367410607253, 7926.286634202949, 8023.117457079833, 8027.39794515402, 9167.816643666956, 10097.89902100999, 10221.182305415698, 10538.209634277306, 10545.99813017762], "unit": "score", "name": "jquery.html", "value": 8609.000207112495}, {"lowerIsBetter": false, "replicates": [1519.5517322389894, 1555.2379334126279, 1741.0, 1837.6416598763246, 1868.5608881912756, 7917.6041197940085, 7945.483543569664, 8175.46859454135, 8177.50934943903, 8217.794555136124, 8265.545395003272, 8292.295154911839, 8322.75031749047, 8407.621657025433, 8409.285210757092, 2363.763623637635, 2415.1426243683504, 2415.7825795678386, 2420.624803155505, 2426.6360045993065, 2430.44099857033, 2450.252672934754, 2472.81453890958, 2473.5671257529953, 2485.6147297168877, 2343.847649902751, 2411.8432301900407, 2424.830261881669, 2453.877306134695, 2478.095495144268, 2328.6856274402994, 2334.9649755253686, 2338.9298321050396, 2355.034435881289, 2368.8223383246327, 2449.7182823975295, 2466.334089795745, 2467.0748469324003, 2474.39377352548, 2478.962815557768, 8131.7560473185895, 8173.67305307787, 8174.550399728013, 8191.098979112293, 8203.097659257477, 5003.0, 5059.494050594948, 5106.770195341219, 5138.409082955471, 5163.0190263849745, 3140.2934339773597, 3162.0, 3179.173414912116, 3184.7770656054086, 3193.4730769423018, 1085.5060947268973, 1093.792179485903, 1101.6254473478975, 1103.2167161315476, 1157.9131565132564, 11268.0, 11373.601923932705, 11391.316521008683, 11392.94303528477, 11418.885811141865, 4404.1411924674385, 4467.106578684276, 4490.3040028795585, 4540.228161212602, 4555.157295900269, 9350.251979841596, 9381.390209636353, 9390.436573805593, 9393.248540116774, 9415.011423800403, 5031.547160755476, 5052.242163675478, 5096.210087436453, 5103.18349064148, 5125.718085505333, 4622.329762184464, 4631.8842028948875, 4697.647676424282, 4699.577038066522, 4713.34013238147, 3724.050367156358, 3736.308782875177, 3773.1510410157493, 3781.6596506314563, 3806.847726090981, 1089.9346039237512, 1132.6149109302876, 1154.3650991954391, 1157.4212893553224, 1163.4531770068054, 1052.5737076484036, 1059.5549869055014, 1128.7347473343757, 1202.9699257518457, 1241.205628397826, 7554.773356799305, 7566.470347075762, 7567.848643027109, 7587.051618547682, 7607.391408687292, 1150.9539618415338, 1153.8211577205548, 1161.703765539782, 1179.2983175010854, 1181.291225264834, 6091.208142941389, 6162.26052873658, 6169.290531588835, 6182.103594978703, 6199.101130336076, 9754.365966212174, 10060.24548158891, 10088.646897358558, 10231.744206394751, 10234.283600147919, 9213.216876565433, 9932.25508086882, 9947.452890091114, 10032.695749552511, 10048.79902401948, 7800.765977020698, 7833.843323133506, 7837.412194085352, 7868.921310786819, 7890.605469726491, 7842.647080881488, 7933.444658874054, 7951.204879512003, 7953.0, 7987.321077708228, 8349.290310323691, 8458.788530286793, 8942.329325300498, 8972.057933916845, 9006.954965225394, 8511.404201705822, 8595.18345757152, 8644.178803013703, 8730.083341249077, 8828.0, 7239.058922340062, 7324.157721862054, 7344.934984427335, 7368.705251789868, 7392.297731715478, 7671.2712292332135, 7683.9231607683205, 7729.381649468141, 7738.264864837832, 7751.0698716152165, 7495.213002634645, 7508.0239568855695, 7592.962035189788, 7632.236776322324, 7740.032495938008, 6673.666316684049, 6750.493712971449, 7153.856922861618, 7185.784426467214, 7330.890036649561, 7393.223711510429, 7406.814829629302, 7407.037085178892, 7435.739749108755, 7453.217412171861, 6825.590464572141, 6854.280300568368, 6856.6228857413325, 6869.416099631588, 6893.20728116253, 6887.104676392037, 6891.827704307432, 6901.930980690129, 6971.372576468142, 7078.893816592652], "unit": "score", "name": "mootools.html", "value": 4738.8489177867505}, {"lowerIsBetter": false, "replicates": [362.9764065335753, 369.04233514031097, 371.2963933346307, 373.482726423903, 376.69487714950884, 6303.275123360815, 6325.304216536178, 6410.070539771737, 6498.122753428286, 6512.869742605145, 6350.491555835454, 6363.045543168516, 6388.936110638892, 6407.423331900128, 6414.839629009265, 4921.532454416824, 4925.187344088239, 4927.063857867011, 4950.727709975949, 4960.999999999991, 4886.144924638192, 4887.975560122194, 4897.118518666639, 4909.239067944474, 4915.877103072416, 4510.842120525782, 4804.423469183703, 4821.566059054684, 4828.661993660445, 4851.442084160331, 4804.015176888729, 4804.423469183703, 4855.927161092604, 4868.902621947541, 4873.975630121862, 4248.129133527619, 4287.764172970486, 4293.119910418388, 4293.141371725667, 4300.0, 5894.115882617642, 5990.64056156632, 6016.909746353808, 6022.78920237794, 6030.306514750816, 5699.74351154199, 5736.397678243766, 5739.454751798614, 5746.051901436258, 5752.511036561856, 6712.160979877515, 6781.813182693009, 6809.250982391933, 6809.761658341934, 6814.829629259259, 4771.427428708577, 4787.066522028206, 4789.928151077702, 4807.759612019385, 4812.302216178635, 6873.965630171867, 7041.788746337618, 7049.118860142482, 7056.470764692617, 7083.6458177090935, 4886.633502487257, 4892.730899800546, 4893.0, 4906.754662266872, 4913.828016019422, 4947.2579113133315, 4975.203967365204, 4983.750812459362, 4985.875353116201, 4986.0, 5599.1041433370465, 5699.145128230715, 5725.914111288334, 5732.426757324234, 5733.741981610838, 6756.76351327711, 6783.457323414115, 6784.287649796799, 6816.386525212754, 6822.590644561342, 1708.8465285931945, 1732.9169269206745, 1750.6323672028761, 1756.4291605228352, 1760.64787042592, 1183.6271574454192, 1190.6428071578664, 1193.6061099837034, 1201.483362154282, 1205.9457324420246, 6441.420272175433, 6462.903056454157, 6467.676616169173, 6468.935310646833, 6475.125858009108, 6927.411170050501, 6959.338862808025, 6981.860362792716, 6989.755358562527, 7004.194517630436, 6348.0, 6369.904451433232, 6420.518461115342, 6426.550141490145, 6458.483321334282, 3762.4761971401626, 3775.6413140751583, 3780.9621903781162, 3798.677112445473, 3804.4483549885667, 7139.96430017857, 7148.678309476086, 7180.5691658500655, 7184.1019872515935, 7239.131304243525, 7039.120109986252, 7149.678264478111, 7185.353318201387, 7185.389241914496, 7200.71197152108, 7217.8917316240295, 7219.602921839244, 7223.711051557984, 7224.0, 7244.49288549807, 6923.792286231421, 6947.965260173667, 6954.130733658293, 6971.163460384583, 6986.580805151707, 6721.260661327152, 6837.418819400406, 6841.521093523405, 6866.6566671667215, 6877.759278425431, 7163.140423149255, 7184.1019872515935, 7199.06412166415, 7208.918662200712, 7230.734621441122, 4903.436104847988, 4909.4845041270155, 4910.116179087799, 4921.458639549575, 4924.310596516419, 6824.2152152503095, 6847.109875716125, 6956.373926346653, 6969.337912898267, 6977.406920411822, 6704.932950670626, 6847.0, 6860.6912688930115, 6901.482388820758, 6907.930920690729, 6823.532940417834, 6844.486663500158, 6844.486663500357, 6860.897086543606, 6883.242843287335, 6587.308332625005, 6695.866082678416, 6739.427148692416, 6765.594064356154, 6766.4248538874745, 6886.414654754402, 6891.517593768589, 6898.413634841095, 6909.998050282781, 6915.51591388598, 6686.231083425469, 6698.66506674674, 6713.160854893138, 6716.798496045127, 6731.091302674076], "unit": "score", "name": "prototype.html", "value": 5136.691699561446}, {"lowerIsBetter": false, "replicates": [712.4229374206892, 716.7419728897596, 787.5314188058103, 807.5639154856378, 872.616048938467, 14604.926975365122, 16129.838701612995, 16267.267972941216, 16281.267342969566, 16310.266038028287, 13726.93136534316, 13882.86117138831, 13922.582322530316, 13935.721285574282, 13965.650858728537, 875.9211670949613, 879.7140929198005, 905.5698543191997, 906.5875026862761, 911.5578944212052, 865.1651156633847, 887.5739644970419, 892.1301730812471, 893.6648756716231, 898.7124120281512, 931.7391130483476, 934.397313732642, 941.9576119074624, 942.5993952570165, 949.3639261694647, 915.839728047589, 929.1219797291562, 939.3659279985982, 941.6845356805478, 941.6939494664227, 681.5093132944272, 682.0519477925687, 682.8019874236466, 690.8169335126196, 701.291695387657, 15131.773023404658, 15156.772648410282, 15187.240637968058, 15246.23768811555, 15256.618584535365, 13599.32003399836, 13788.655283617889, 13808.861911380958, 13851.238181899991, 13860.861391386057, 10907.18196135293, 11317.603883864025, 11435.828462573068, 11507.539698412054, 11736.471858766376, 3843.8846834595006, 3856.382978723391, 3865.729398942075, 3868.516435445569, 3877.0, 51085.48914510845, 51155.74422127903, 51294.48705512972, 51470.22794658083, 51515.74242128765, 10717.196210284259, 10735.624253151102, 10746.355218686904, 10859.18556108295, 11041.282316649393, 18862.71705924412, 18934.24263029491, 18935.147918343708, 18995.905020475086, 19000.52498687544, 7746.49647772893, 7805.297523222937, 7844.882326765103, 7879.527228366202, 8137.471064380797, 4439.289713645801, 4479.910401792011, 4491.910161796811, 4516.412866327357, 4549.067441174551, 3126.7185953264316, 3130.4208721386617, 3156.210947263184, 3213.4697774867354, 3233.0785726067957, 590.4892268187987, 612.0146564031905, 612.063542779548, 613.6440864298785, 618.4619381138349, 611.3366996808423, 637.6397335505425, 643.234550884446, 644.1432894250636, 654.7021105397033, 7839.960800195962, 7974.362051035903, 8154.8776768348525, 8164.918350816416, 8236.299914507214, 554.9778008879599, 560.3975726094465, 601.8525461262019, 732.0958616109101, 760.5398733766102, 4262.531121576624, 4382.737035777864, 4511.120331535385, 4668.97665511677, 4704.223803072455, 58711.41288587145, 59533.0, 59768.40231597715, 60161.097583536284, 60168.0, 55418.72290638521, 55489.445105548424, 55513.722431387585, 56725.0, 56962.0, 48676.02647947021, 49091.263631045564, 49798.50201498011, 49856.252156217684, 49991.750041250285, 9734.41593504392, 9840.507974601098, 10035.648752293635, 10040.046195611405, 10119.443430611387, 35070.649293506736, 35203.82398088096, 36327.45508817317, 36395.09012274714, 36830.26339473142, 45448.31827522524, 47786.522134778206, 47789.0, 47909.0, 48118.0, 11916.642500724993, 12172.0, 12200.084993625691, 12308.938455307667, 12446.502139914302, 13277.0, 13429.059965802691, 13548.525801596898, 13901.860981390057, 14021.158730476203, 12557.372131393577, 12747.745045099231, 12797.8080328797, 12824.29466379358, 12920.547780827626, 12400.81398779001, 12417.875821241672, 12567.057470689551, 12583.811242831182, 12707.046971476992, 12496.25022498653, 12736.172148810298, 12770.553030643883, 12943.223406595635, 12997.935010324887, 9796.706098817047, 10030.348027378197, 10156.593736250466, 10454.843177352193, 10563.524641391003, 9993.850092248476, 10644.946775266075, 10799.784004319712, 10818.350898946088, 10986.615468458565], "unit": "score", "name": "yui.html", "value": 6783.749624013643}], "name": "dromaeo_css", "value": 7257.340069518427}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 105.0, 111.0, 110.0, 111.0, 123.0, 111.0, 111.0, 111.0, 109.0], "unit": "score", "name": "ai-astar", "value": 111.3}, {"lowerIsBetter": true, "replicates": [163.0, 127.0, 151.0, 171.0, 129.0, 155.0, 153.0, 136.0, 157.0, 126.0], "unit": "score", "name": "audio-beat-detection", "value": 146.8}, {"lowerIsBetter": true, "replicates": [161.0, 166.0, 163.0, 163.0, 163.0, 162.0, 162.0, 163.0, 165.0, 163.0], "unit": "score", "name": "audio-dft", "value": 163.1}, {"lowerIsBetter": true, "replicates": [73.0, 74.0, 76.0, 161.0, 77.0, 73.0, 73.0, 74.0, 161.0, 75.0], "unit": "score", "name": "audio-fft", "value": 91.7}, {"lowerIsBetter": true, "replicates": [94.0, 96.0, 141.0, 95.0, 95.0, 95.0, 140.0, 94.0, 95.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 108.2}, {"lowerIsBetter": true, "replicates": [111.0, 110.0, 110.0, 110.0, 111.0, 110.0, 109.0, 109.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 148.0, 146.0, 146.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 146.7}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 97.0, 96.0, 97.0, 97.0, 97.0, 97.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.6}, {"lowerIsBetter": true, "replicates": [60.0, 54.0, 61.0, 57.0, 57.0, 57.0, 59.0, 60.0, 56.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 57.6}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 65.0, 65.0, 65.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.6}, {"lowerIsBetter": true, "replicates": [83.0, 80.0, 81.0, 89.0, 81.0, 86.0, 82.0, 82.0, 82.0, 81.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.7}, {"lowerIsBetter": true, "replicates": [117.0, 127.0, 118.0, 120.0, 112.0, 123.0, 118.0, 112.0, 111.0, 111.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 116.9}, {"lowerIsBetter": true, "replicates": [135.0, 138.0, 146.0, 137.0, 147.0, 125.0, 133.0, 136.0, 136.0, 126.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 135.9}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 67.0, 66.0, 74.0, 64.0, 64.0, 64.0, 64.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.0}], "name": "kraken", "value": 1498.1000000000004}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.02856188408217], "unit": "score", "name": "Richards", "value": 211.35307400000002}, {"lowerIsBetter": false, "replicates": [170.64846416382252], "unit": "score", "name": "DeltaBlue", "value": 387.45148000000006}, {"lowerIsBetter": false, "replicates": [278.31895352073474], "unit": "score", "name": "Encrypt", "value": 956.3883330000003}, {"lowerIsBetter": false, "replicates": [5235.602094240838], "unit": "score", "name": "Decrypt", "value": 50.84057100000005}, {"lowerIsBetter": false, "replicates": [949.667616334283], "unit": "score", "name": "RayTrace", "value": 779.2084170000002}, {"lowerIsBetter": false, "replicates": [553.4034311012729], "unit": "score", "name": "Earley", "value": 1204.2986409999994}, {"lowerIsBetter": false, "replicates": [11258.426966292134], "unit": "score", "name": "Boyer", "value": 59.196813373253526}, {"lowerIsBetter": false, "replicates": [39076.92307692308], "unit": "score", "name": "RegExp", "value": 23.312608267716538}, {"lowerIsBetter": false, "replicates": [533.8983050847457], "unit": "score", "name": "Splay", "value": 152.63393650793657}, {"lowerIsBetter": false, "replicates": [4854.368932038835], "unit": "score", "name": "NavierStokes", "value": 305.7040000000001}], "name": "v8_7", "value": 21899.206639410706}]} 09:42:11 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,43]]", "result": "21899.21"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,43]]", "result": "1498.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,43]]", "result": "7257.34"}}} 09:42:11 INFO - Return code: 0 09:42:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 09:42:11 INFO - # TBPL SUCCESS # 09:42:11 INFO - Running post-action listener: _resource_record_post_action 09:42:11 INFO - Running post-run listener: _resource_record_post_run 09:42:12 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 0; Write bytes: 502349824; Read time: 0; Write time: 394248 09:42:12 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 249069568; Read time: 0; Write time: 251928 09:42:12 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 0; Write bytes: 253280256; Read time: 0; Write time: 142320 09:42:12 INFO - Running post-run listener: _upload_blobber_files 09:42:12 INFO - Blob upload gear active. 09:42:12 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 09:42:12 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 09:42:12 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', 'Mozilla-Aurora', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 09:42:12 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 Mozilla-Aurora -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 09:42:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:42:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:42:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:42:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:14 INFO - (blobuploader) - INFO - Done attempting. 09:42:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:42:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:42:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:14 INFO - (blobuploader) - INFO - Done attempting. 09:42:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:42:14 INFO - Return code: 0 09:42:14 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 09:42:14 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 09:42:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13cadd6e41deb25076cfede96ccfe2132d0cbd78f4bee670e7cf73549f36a04b79ffad1794bee9b1e39d1a6ff0cb2d98243a8794d1d90a91a39c1f909e3ee1d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9baac55693432fb384502f17800deac3ef2a3db7e65723729f33e1abfed3e7f2c188f510c4371dac573b914d16e12e70b8a3f85aa5118e5458abc802d8ca290a"} 09:42:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 09:42:14 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 09:42:14 INFO - Contents: 09:42:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13cadd6e41deb25076cfede96ccfe2132d0cbd78f4bee670e7cf73549f36a04b79ffad1794bee9b1e39d1a6ff0cb2d98243a8794d1d90a91a39c1f909e3ee1d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9baac55693432fb384502f17800deac3ef2a3db7e65723729f33e1abfed3e7f2c188f510c4371dac573b914d16e12e70b8a3f85aa5118e5458abc802d8ca290a"} 09:42:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:42:14 INFO - Copying logs to upload dir... 09:42:14 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 09:42:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.296140 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-02-29 09:42:13.274904) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-29 09:42:13.278212) ========= 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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13cadd6e41deb25076cfede96ccfe2132d0cbd78f4bee670e7cf73549f36a04b79ffad1794bee9b1e39d1a6ff0cb2d98243a8794d1d90a91a39c1f909e3ee1d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9baac55693432fb384502f17800deac3ef2a3db7e65723729f33e1abfed3e7f2c188f510c4371dac573b914d16e12e70b8a3f85aa5118e5458abc802d8ca290a"} build_url:https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011531 build_url: 'https://queue.taskcluster.net/v1/task/R9g2aRb9TkGG55RlAEqlJA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13cadd6e41deb25076cfede96ccfe2132d0cbd78f4bee670e7cf73549f36a04b79ffad1794bee9b1e39d1a6ff0cb2d98243a8794d1d90a91a39c1f909e3ee1d0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9baac55693432fb384502f17800deac3ef2a3db7e65723729f33e1abfed3e7f2c188f510c4371dac573b914d16e12e70b8a3f85aa5118e5458abc802d8ca290a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-29 09:42:13.304163) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:42:13.304505) ========= 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=523ca41ca1e0623de333ad52000001bf-1456766471.130437-1209476087 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004418 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-29 09:42:13.389707) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-29 09:42:13.390049) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-29 09:42:13.390374) ========= ========= Total master_lag: 0.28 =========