builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-024 starttime: 1457114112.54 results: success (0) buildid: 20160304073420 builduid: 91289db578374c82912f00bc39fd5727 revision: 38bc572c312206a89c09b6c280840970ff12fc5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.544810) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.545797) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.546513) ========= 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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.005581 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.573716) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.573978) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.608100) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.608353) ========= 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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009033 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.662414) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.663096) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.663699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.663966) ========= 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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-04 09:55:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 112M=0s 2016-03-04 09:55:12 (112 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155669 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.834868) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.835177) ========= 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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.068180 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.914260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:12.914956) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 38bc572c312206a89c09b6c280840970ff12fc5f --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 38bc572c312206a89c09b6c280840970ff12fc5f --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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-04 09:55:12,990 truncating revision to first 12 chars 2016-03-04 09:55:12,990 Setting DEBUG logging. 2016-03-04 09:55:12,990 attempt 1/10 2016-03-04 09:55:12,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/38bc572c3122?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-04 09:55:13,257 unpacking tar archive at: mozilla-aurora-38bc572c3122/testing/mozharness/ program finished with exit code 0 elapsedTime=0.553688 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:13.483445) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:13.483802) ========= echo 38bc572c312206a89c09b6c280840970ff12fc5f in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'38bc572c312206a89c09b6c280840970ff12fc5f'] 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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 38bc572c312206a89c09b6c280840970ff12fc5f program finished with exit code 0 elapsedTime=0.004208 script_repo_revision: '38bc572c312206a89c09b6c280840970ff12fc5f' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:13.534242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:13.534520) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-04 09:55:13.544532) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2016-03-04 09:55:13.544820) ========= /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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:55:13 INFO - MultiFileLogger online at 20160304 09:55:13 in /builds/slave/test-pgo 09:55: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:55:13 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 09:55:13 INFO - {'append_to_log': False, 09:55:13 INFO - 'base_work_dir': '/builds/slave/test-pgo', 09:55:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:55:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 09:55:13 INFO - 'branch': 'Mozilla-Aurora', 09:55:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:55:13 INFO - 'config_files': ('talos/linux_config.py',), 09:55:13 INFO - 'default_actions': ('clobber', 09:55:13 INFO - 'read-buildbot-config', 09:55:13 INFO - 'download-and-extract', 09:55:13 INFO - 'populate-webroot', 09:55:13 INFO - 'create-virtualenv', 09:55:13 INFO - 'install', 09:55:13 INFO - 'run-tests'), 09:55:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:55:13 INFO - 'download_minidump_stackwalk': True, 09:55:13 INFO - 'download_symbols': 'ondemand', 09:55:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:55:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:55:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:55:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:55:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:55:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:55:13 INFO - 'installer_path': 'installer.exe', 09:55:13 INFO - 'log_level': 'info', 09:55:13 INFO - 'log_name': 'talos', 09:55:13 INFO - 'log_to_console': True, 09:55:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:55:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:55:13 INFO - 'opt_config_files': (), 09:55:13 INFO - 'pip_index': False, 09:55:13 INFO - 'sps_profile': False, 09:55:13 INFO - 'sps_profile_interval': 0, 09:55:13 INFO - 'suite': 'dromaeojs-e10s', 09:55:13 INFO - 'system_bits': '32', 09:55:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:55:13 INFO - 'title': 'talos-linux64-ix-024', 09:55:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:55:13 INFO - 'use_talos_json': True, 09:55:13 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 09:55:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:55:13 INFO - 'work_dir': 'build'} 09:55:13 INFO - ##### 09:55:13 INFO - ##### Running clobber step. 09:55:13 INFO - ##### 09:55:13 INFO - Running pre-action listener: _resource_record_pre_action 09:55:13 INFO - Running main action method: clobber 09:55:13 INFO - rmtree: /builds/slave/test-pgo/build 09:55:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 09:55:14 INFO - Running post-action listener: _resource_record_post_action 09:55:14 INFO - ##### 09:55:14 INFO - ##### Running read-buildbot-config step. 09:55:14 INFO - ##### 09:55:14 INFO - Running pre-action listener: _resource_record_pre_action 09:55:14 INFO - Running main action method: read_buildbot_config 09:55:14 INFO - Using buildbot properties: 09:55:14 INFO - { 09:55:14 INFO - "project": "", 09:55:14 INFO - "product": "firefox", 09:55:14 INFO - "script_repo_revision": "production", 09:55:14 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 09:55:14 INFO - "repository": "", 09:55:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 09:55:14 INFO - "buildid": "20160304073420", 09:55:14 INFO - "pgo_build": "True", 09:55:14 INFO - "basedir": "/builds/slave/test-pgo", 09:55:14 INFO - "buildnumber": 207, 09:55:14 INFO - "slavename": "talos-linux64-ix-024", 09:55:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:55:14 INFO - "platform": "ubuntu64_hw", 09:55:14 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:55:14 INFO - "branch": "mozilla-aurora", 09:55:14 INFO - "repo_path": "releases/mozilla-aurora", 09:55:14 INFO - "revision": "38bc572c312206a89c09b6c280840970ff12fc5f", 09:55:14 INFO - "stage_platform": "linux64-pgo", 09:55:14 INFO - "builduid": "91289db578374c82912f00bc39fd5727", 09:55:14 INFO - "slavebuilddir": "test" 09:55:14 INFO - } 09:55:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2. 09:55:14 INFO - Running post-action listener: _resource_record_post_action 09:55:14 INFO - ##### 09:55:14 INFO - ##### Running download-and-extract step. 09:55:14 INFO - ##### 09:55:14 INFO - Running pre-action listener: _resource_record_pre_action 09:55:14 INFO - Running main action method: download_and_extract 09:55:14 INFO - mkdir: /builds/slave/test-pgo/build/tests 09:55: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:55:14 INFO - https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 09:55:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/test_packages.json 09:55:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/test_packages.json 09:55:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 09:55:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 09:55:19 INFO - Downloaded 1448 bytes. 09:55:19 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 09:55:19 INFO - Using the following test package requirements: 09:55:19 INFO - {u'common': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 09:55:19 INFO - u'cppunittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:19 INFO - u'firefox-46.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:55:19 INFO - u'gtest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:19 INFO - u'firefox-46.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:55:19 INFO - u'jittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:19 INFO - u'jsshell-linux-x86_64.zip'], 09:55:19 INFO - u'mochitest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:19 INFO - u'firefox-46.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:55:19 INFO - u'mozbase': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 09:55:19 INFO - u'reftest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:19 INFO - u'firefox-46.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:55:19 INFO - u'talos': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:19 INFO - u'firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:55:19 INFO - u'web-platform': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:19 INFO - u'firefox-46.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:55:19 INFO - u'webapprt': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 09:55:19 INFO - u'xpcshell': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 09:55:19 INFO - u'firefox-46.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:55:19 INFO - Downloading packages: [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:55: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:55:19 INFO - https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:55:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:55:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:55:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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:55:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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:55:22 INFO - Downloaded 23089466 bytes. 09:55:22 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:55:22 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:55:23 INFO - Return code: 0 09:55:23 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:55:23 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:55:23 INFO - https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:55:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:55:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 09:55:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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:55:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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:55:57 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip: The read operation timed out 09:55:57 INFO - retry: attempt #1 caught exception: The read operation timed out 09:55:57 INFO - retry: Failed, sleeping 30 seconds before retrying 09:56:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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 #2 09:56:28 INFO - Downloaded 23089466 bytes. 09:56:28 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:56:28 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:56:29 INFO - Return code: 0 09:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:29 INFO - https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:56:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 09:56:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 09:56:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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:56:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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:56:30 INFO - Downloaded 10801536 bytes. 09:56:30 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:56:30 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:56:31 INFO - Return code: 0 09:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:31 INFO - https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:56:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:56:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:56:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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:56:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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:56:32 INFO - Downloaded 59973397 bytes. 09:56:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:56:32 INFO - mkdir: /builds/slave/test-pgo/properties 09:56:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 09:56:32 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 09:56:32 INFO - Contents: 09:56:32 INFO - build_url:https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 09:56:32 INFO - Running post-action listener: _resource_record_post_action 09:56:32 INFO - Running post-action listener: set_extra_try_arguments 09:56:32 INFO - ##### 09:56:32 INFO - ##### Running populate-webroot step. 09:56:32 INFO - ##### 09:56:32 INFO - Running pre-action listener: _resource_record_pre_action 09:56:32 INFO - Running main action method: populate_webroot 09:56:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:56:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:56:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:56:33 INFO - '--tppagecycles', 09:56:33 INFO - '7'], 09:56:33 INFO - 'tests': ['tsvgm']}}, 09:56:33 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:56:33 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:56:33 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:56:33 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 09:56:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:33 INFO - 'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:33 INFO - 'tests': ['damp', 'tps']}, 09:56:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:33 INFO - 'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['damp', 'tps']}, 09:56:33 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:56:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:56:33 INFO - 'other': {'tests': ['a11yr', 09:56:33 INFO - 'ts_paint', 09:56:33 INFO - 'tpaint', 09:56:33 INFO - 'sessionrestore', 09:56:33 INFO - 'sessionrestore_no_auto_restore']}, 09:56:33 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['a11yr', 09:56:33 INFO - 'ts_paint', 09:56:33 INFO - 'tpaint', 09:56:33 INFO - 'sessionrestore', 09:56:33 INFO - 'sessionrestore_no_auto_restore']}, 09:56:33 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['a11yr', 09:56:33 INFO - 'ts_paint', 09:56:33 INFO - 'tpaint', 09:56:33 INFO - 'sessionrestore', 09:56:33 INFO - 'sessionrestore_no_auto_restore']}, 09:56:33 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['a11yr', 09:56:33 INFO - 'ts_paint', 09:56:33 INFO - 'tpaint', 09:56:33 INFO - 'sessionrestore', 09:56:33 INFO - 'sessionrestore_no_auto_restore']}, 09:56:33 INFO - 'other_l64': {'tests': ['a11yr', 09:56:33 INFO - 'ts_paint', 09:56:33 INFO - 'tpaint', 09:56:33 INFO - 'sessionrestore', 09:56:33 INFO - 'sessionrestore_no_auto_restore']}, 09:56:33 INFO - 'other_nol64': {'tests': ['a11yr', 09:56:33 INFO - 'ts_paint', 09:56:33 INFO - 'tpaint', 09:56:33 INFO - 'sessionrestore', 09:56:33 INFO - 'sessionrestore_no_auto_restore']}, 09:56:33 INFO - 'svgr': {'tests': ['tsvgx', 09:56:33 INFO - 'tsvgr_opacity', 09:56:33 INFO - 'tart', 09:56:33 INFO - 'tscrollx', 09:56:33 INFO - 'cart']}, 09:56:33 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['tsvgx', 09:56:33 INFO - 'tsvgr_opacity', 09:56:33 INFO - 'tart', 09:56:33 INFO - 'tscrollx', 09:56:33 INFO - 'cart']}, 09:56:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:33 INFO - 'tests': ['tp5o']}, 09:56:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:33 INFO - 'talos_options': ['--e10s'], 09:56:33 INFO - 'tests': ['tp5o']}, 09:56:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:33 INFO - 'talos_options': ['--xperf_path', 09:56:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:33 INFO - 'tests': ['tp5n']}, 09:56:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:33 INFO - 'talos_options': ['--e10s', 09:56:33 INFO - '--xperf_path', 09:56:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:33 INFO - 'tests': ['tp5n']}}, 09:56:33 INFO - 'talos.zip': {'path': '', 09:56:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:56:33 INFO - Running post-action listener: _resource_record_post_action 09:56:33 INFO - ##### 09:56:33 INFO - ##### Running create-virtualenv step. 09:56:33 INFO - ##### 09:56:33 INFO - Running pre-action listener: _resource_record_pre_action 09:56:33 INFO - Running main action method: create_virtualenv 09:56:33 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 09:56:33 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:56:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 09:56:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:56:33 INFO - Using real prefix '/usr' 09:56:33 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 09:56:33 INFO - Installing distribute.............................................................................................................................................................................................done. 09:56:35 INFO - Installing pip.................done. 09:56:35 INFO - Return code: 0 09:56:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 09:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:35 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:35 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:56:35 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:56:35 INFO - Using env: {'DISPLAY': ':0', 09:56:35 INFO - 'HOME': '/home/cltbld', 09:56:35 INFO - 'LANG': 'en_US.UTF-8', 09:56:35 INFO - 'LANGUAGE': 'en_US:en', 09:56:35 INFO - 'LOGNAME': 'cltbld', 09:56:35 INFO - 'MAIL': '/var/mail/cltbld', 09:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:35 INFO - 'MOZ_NO_REMOTE': '1', 09:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:35 INFO - 'NO_EM_RESTART': '1', 09:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:35 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:35 INFO - 'SHELL': '/bin/bash', 09:56:35 INFO - 'SHLVL': '1', 09:56:35 INFO - 'TERM': 'linux', 09:56:35 INFO - 'TMOUT': '86400', 09:56:35 INFO - 'USER': 'cltbld', 09:56:35 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:35 INFO - '_': '/tools/buildbot/bin/python'} 09:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:35 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:56:35 INFO - Cleaning up... 09:56:35 INFO - Return code: 0 09:56:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 09:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:35 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:35 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:56:35 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:56:35 INFO - Using env: {'DISPLAY': ':0', 09:56:35 INFO - 'HOME': '/home/cltbld', 09:56:35 INFO - 'LANG': 'en_US.UTF-8', 09:56:35 INFO - 'LANGUAGE': 'en_US:en', 09:56:35 INFO - 'LOGNAME': 'cltbld', 09:56:35 INFO - 'MAIL': '/var/mail/cltbld', 09:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:35 INFO - 'MOZ_NO_REMOTE': '1', 09:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:35 INFO - 'NO_EM_RESTART': '1', 09:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:35 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:35 INFO - 'SHELL': '/bin/bash', 09:56:35 INFO - 'SHLVL': '1', 09:56:35 INFO - 'TERM': 'linux', 09:56:35 INFO - 'TMOUT': '86400', 09:56:35 INFO - 'USER': 'cltbld', 09:56:35 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:35 INFO - '_': '/tools/buildbot/bin/python'} 09:56:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:36 INFO - Downloading/unpacking psutil>=0.7.1 09:56:36 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:56:36 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:56:36 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:56:36 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:56:36 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:56:36 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:56:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 09:56:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:37 INFO - Installing collected packages: psutil 09:56:37 INFO - Running setup.py install for psutil 09:56:38 INFO - building 'psutil._psutil_linux' extension 09:56:38 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:56:38 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:56:38 INFO - building 'psutil._psutil_posix' extension 09:56:38 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:56:38 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:56:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:38 INFO - Successfully installed psutil 09:56:38 INFO - Cleaning up... 09:56:38 INFO - Return code: 0 09:56:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 09:56:38 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:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:38 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:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:38 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:38 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:56:38 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:56:38 INFO - Using env: {'DISPLAY': ':0', 09:56:38 INFO - 'HOME': '/home/cltbld', 09:56:38 INFO - 'LANG': 'en_US.UTF-8', 09:56:38 INFO - 'LANGUAGE': 'en_US:en', 09:56:38 INFO - 'LOGNAME': 'cltbld', 09:56:38 INFO - 'MAIL': '/var/mail/cltbld', 09:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:38 INFO - 'MOZ_NO_REMOTE': '1', 09:56:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:38 INFO - 'NO_EM_RESTART': '1', 09:56:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:38 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:38 INFO - 'SHELL': '/bin/bash', 09:56:38 INFO - 'SHLVL': '1', 09:56:38 INFO - 'TERM': 'linux', 09:56:38 INFO - 'TMOUT': '86400', 09:56:38 INFO - 'USER': 'cltbld', 09:56:38 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:38 INFO - '_': '/tools/buildbot/bin/python'} 09:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:38 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:56:38 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:56:38 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:56:38 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:56:38 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:56:38 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:56:38 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:56:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:56:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:56:40 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:56:40 INFO - Installing collected packages: mozsystemmonitor 09:56:40 INFO - Running setup.py install for mozsystemmonitor 09:56:40 INFO - Successfully installed mozsystemmonitor 09:56:40 INFO - Cleaning up... 09:56:40 INFO - Return code: 0 09:56:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 09:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:40 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:40 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:56:40 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:56:40 INFO - Using env: {'DISPLAY': ':0', 09:56:40 INFO - 'HOME': '/home/cltbld', 09:56:40 INFO - 'LANG': 'en_US.UTF-8', 09:56:40 INFO - 'LANGUAGE': 'en_US:en', 09:56:40 INFO - 'LOGNAME': 'cltbld', 09:56:40 INFO - 'MAIL': '/var/mail/cltbld', 09:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:40 INFO - 'MOZ_NO_REMOTE': '1', 09:56:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:40 INFO - 'NO_EM_RESTART': '1', 09:56:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:40 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:40 INFO - 'SHELL': '/bin/bash', 09:56:40 INFO - 'SHLVL': '1', 09:56:40 INFO - 'TERM': 'linux', 09:56:40 INFO - 'TMOUT': '86400', 09:56:40 INFO - 'USER': 'cltbld', 09:56:40 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:40 INFO - '_': '/tools/buildbot/bin/python'} 09:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:40 INFO - Downloading/unpacking blobuploader==1.2.4 09:56:40 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:56:40 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:56:40 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:56:40 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:56:40 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:56:40 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:56:42 INFO - Downloading blobuploader-1.2.4.tar.gz 09:56:42 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:56:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:56:42 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:56:42 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:56:42 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:56:42 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:56:42 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:56:42 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:56:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 09:56:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:56: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:56: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:56: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:56: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:56: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:56: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:56:43 INFO - Downloading docopt-0.6.1.tar.gz 09:56:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 09:56:43 INFO - Installing collected packages: blobuploader, requests, docopt 09:56:43 INFO - Running setup.py install for blobuploader 09:56:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:56:43 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 09:56:43 INFO - Running setup.py install for requests 09:56:43 INFO - Running setup.py install for docopt 09:56:44 INFO - Successfully installed blobuploader requests docopt 09:56:44 INFO - Cleaning up... 09:56:44 INFO - Return code: 0 09:56:44 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:56: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:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:44 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:56:44 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:56:44 INFO - Using env: {'DISPLAY': ':0', 09:56:44 INFO - 'HOME': '/home/cltbld', 09:56:44 INFO - 'LANG': 'en_US.UTF-8', 09:56:44 INFO - 'LANGUAGE': 'en_US:en', 09:56:44 INFO - 'LOGNAME': 'cltbld', 09:56:44 INFO - 'MAIL': '/var/mail/cltbld', 09:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:44 INFO - 'MOZ_NO_REMOTE': '1', 09:56:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:44 INFO - 'NO_EM_RESTART': '1', 09:56:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:44 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:44 INFO - 'SHELL': '/bin/bash', 09:56:44 INFO - 'SHLVL': '1', 09:56:44 INFO - 'TERM': 'linux', 09:56:44 INFO - 'TMOUT': '86400', 09:56:44 INFO - 'USER': 'cltbld', 09:56:44 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:44 INFO - '_': '/tools/buildbot/bin/python'} 09:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:56:44 INFO - Running setup.py (path:/tmp/pip-MKb6Yz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:56:44 INFO - Running setup.py (path:/tmp/pip-n2CPVo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:56:44 INFO - Running setup.py (path:/tmp/pip-_AVH5K-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:56:44 INFO - Running setup.py (path:/tmp/pip-ctIsJP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:56:44 INFO - Running setup.py (path:/tmp/pip-va5xPk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:56:44 INFO - Running setup.py (path:/tmp/pip-8w76Qj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:56:44 INFO - Running setup.py (path:/tmp/pip-v_O7u2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:56:44 INFO - Running setup.py (path:/tmp/pip-xGCqml-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:56:44 INFO - Running setup.py (path:/tmp/pip-_5T_2Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:56:44 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:56:44 INFO - Running setup.py (path:/tmp/pip-Aa1qxT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:56:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:56:45 INFO - Running setup.py (path:/tmp/pip-K8bnpF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:56:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:56:45 INFO - Running setup.py (path:/tmp/pip-gSPTh1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:56:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:56:45 INFO - Running setup.py (path:/tmp/pip-hpQiDa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:56:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:56:45 INFO - Running setup.py (path:/tmp/pip-xc5YNy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:56:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:56:45 INFO - Running setup.py (path:/tmp/pip-bqtAkj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:56:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:56:45 INFO - Running setup.py (path:/tmp/pip-US4UVi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:56:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:56:45 INFO - Running setup.py (path:/tmp/pip-R5o5bJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:56:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:45 INFO - Running setup.py install for manifestparser 09:56:45 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 09:56:45 INFO - Running setup.py install for mozcrash 09:56:45 INFO - Running setup.py install for mozdebug 09:56:45 INFO - Running setup.py install for mozdevice 09:56:45 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 09:56:45 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 09:56:45 INFO - Running setup.py install for mozfile 09:56:46 INFO - Running setup.py install for mozhttpd 09:56:46 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Running setup.py install for mozinfo 09:56:46 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Running setup.py install for mozInstall 09:56:46 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Running setup.py install for mozleak 09:56:46 INFO - Running setup.py install for mozlog 09:56:46 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Running setup.py install for moznetwork 09:56:46 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Running setup.py install for mozprocess 09:56:46 INFO - Running setup.py install for mozprofile 09:56:46 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 09:56:46 INFO - Running setup.py install for mozrunner 09:56:46 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 09:56:47 INFO - Running setup.py install for mozscreenshot 09:56:47 INFO - Running setup.py install for moztest 09:56:47 INFO - Running setup.py install for mozversion 09:56:47 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 09:56:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:47 INFO - Cleaning up... 09:56:47 INFO - Return code: 0 09:56:47 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:56:47 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:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:47 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:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:47 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:47 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:56:47 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:56:47 INFO - Using env: {'DISPLAY': ':0', 09:56:47 INFO - 'HOME': '/home/cltbld', 09:56:47 INFO - 'LANG': 'en_US.UTF-8', 09:56:47 INFO - 'LANGUAGE': 'en_US:en', 09:56:47 INFO - 'LOGNAME': 'cltbld', 09:56:47 INFO - 'MAIL': '/var/mail/cltbld', 09:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:47 INFO - 'MOZ_NO_REMOTE': '1', 09:56:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:47 INFO - 'NO_EM_RESTART': '1', 09:56:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:47 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:47 INFO - 'SHELL': '/bin/bash', 09:56:47 INFO - 'SHLVL': '1', 09:56:47 INFO - 'TERM': 'linux', 09:56:47 INFO - 'TMOUT': '86400', 09:56:47 INFO - 'USER': 'cltbld', 09:56:47 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:47 INFO - '_': '/tools/buildbot/bin/python'} 09:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:56:47 INFO - Running setup.py (path:/tmp/pip-RhTsDH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 09:56:47 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:56:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:56:47 INFO - Running setup.py (path:/tmp/pip-wilT49-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 09:56:47 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:56:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:56:47 INFO - Running setup.py (path:/tmp/pip-ol65I0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 09:56:47 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:56:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:56:47 INFO - Running setup.py (path:/tmp/pip-KoG43Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 09:56:47 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:56:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 09:56:47 INFO - Running setup.py (path:/tmp/pip-Ny5the-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 09:56:47 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:56:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:56:47 INFO - Running setup.py (path:/tmp/pip-LscPlM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 09:56:47 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:56:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:56:47 INFO - Running setup.py (path:/tmp/pip-LbNKPi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:56:48 INFO - Running setup.py (path:/tmp/pip-yFP7Ql-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 09:56:48 INFO - Running setup.py (path:/tmp/pip-IEryky-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 09:56:48 INFO - Running setup.py (path:/tmp/pip-pg_VU9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:56:48 INFO - Running setup.py (path:/tmp/pip-D1WfZe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:56:48 INFO - Running setup.py (path:/tmp/pip-tx4vl3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:56:48 INFO - Running setup.py (path:/tmp/pip-CdvRTW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:56:48 INFO - Running setup.py (path:/tmp/pip-9wsXeP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:56:48 INFO - Running setup.py (path:/tmp/pip-mKBltk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 09:56:48 INFO - Running setup.py (path:/tmp/pip-YmAuig-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 09:56:48 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:56:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 09:56:48 INFO - Running setup.py (path:/tmp/pip-KhR5aC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 09:56:48 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:56:48 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:56:48 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:56:48 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:56:48 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:56:48 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:56:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 09:56:48 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:56:48 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:56:48 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:56:48 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:56:48 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:56:48 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:56:50 INFO - Downloading blessings-1.6.tar.gz 09:56:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:50 INFO - Installing collected packages: blessings 09:56:50 INFO - Running setup.py install for blessings 09:56:50 INFO - Successfully installed blessings 09:56:50 INFO - Cleaning up... 09:56:50 INFO - Return code: 0 09:56:50 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 09:56:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:56:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:56:51 INFO - Reading from file tmpfile_stdout 09:56:51 INFO - Current package versions: 09:56:51 INFO - argparse == 1.2.1 09:56:51 INFO - blessings == 1.6 09:56:51 INFO - blobuploader == 1.2.4 09:56:51 INFO - docopt == 0.6.1 09:56:51 INFO - manifestparser == 1.1 09:56:51 INFO - mozInstall == 1.12 09:56:51 INFO - mozcrash == 0.16 09:56:51 INFO - mozdebug == 0.1 09:56:51 INFO - mozdevice == 0.48 09:56:51 INFO - mozfile == 1.2 09:56:51 INFO - mozhttpd == 0.7 09:56:51 INFO - mozinfo == 0.9 09:56:51 INFO - mozleak == 0.1 09:56:51 INFO - mozlog == 3.1 09:56:51 INFO - moznetwork == 0.27 09:56:51 INFO - mozprocess == 0.22 09:56:51 INFO - mozprofile == 0.28 09:56:51 INFO - mozrunner == 6.11 09:56:51 INFO - mozscreenshot == 0.1 09:56:51 INFO - mozsystemmonitor == 0.0 09:56:51 INFO - moztest == 0.7 09:56:51 INFO - mozversion == 1.4 09:56:51 INFO - psutil == 3.1.1 09:56:51 INFO - requests == 1.2.3 09:56:51 INFO - wsgiref == 0.1.2 09:56:51 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 09:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:51 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:51 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:56:51 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:56:51 INFO - Using env: {'DISPLAY': ':0', 09:56:51 INFO - 'HOME': '/home/cltbld', 09:56:51 INFO - 'LANG': 'en_US.UTF-8', 09:56:51 INFO - 'LANGUAGE': 'en_US:en', 09:56:51 INFO - 'LOGNAME': 'cltbld', 09:56:51 INFO - 'MAIL': '/var/mail/cltbld', 09:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:51 INFO - 'MOZ_NO_REMOTE': '1', 09:56:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:51 INFO - 'NO_EM_RESTART': '1', 09:56:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:51 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:51 INFO - 'SHELL': '/bin/bash', 09:56:51 INFO - 'SHLVL': '1', 09:56:51 INFO - 'TERM': 'linux', 09:56:51 INFO - 'TMOUT': '86400', 09:56:51 INFO - 'USER': 'cltbld', 09:56:51 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:51 INFO - '_': '/tools/buildbot/bin/python'} 09:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:51 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:56:51 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:56:51 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:56:51 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:56:51 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:56:51 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:56:51 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:56:51 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:56:51 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:56:51 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:56:51 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:56:51 INFO - Cleaning up... 09:56:51 INFO - Return code: 0 09:56:51 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 09:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:51 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:51 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:56:51 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:56:51 INFO - Using env: {'DISPLAY': ':0', 09:56:51 INFO - 'HOME': '/home/cltbld', 09:56:51 INFO - 'LANG': 'en_US.UTF-8', 09:56:51 INFO - 'LANGUAGE': 'en_US:en', 09:56:51 INFO - 'LOGNAME': 'cltbld', 09:56:51 INFO - 'MAIL': '/var/mail/cltbld', 09:56:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:51 INFO - 'MOZ_NO_REMOTE': '1', 09:56:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:51 INFO - 'NO_EM_RESTART': '1', 09:56:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:51 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:51 INFO - 'SHELL': '/bin/bash', 09:56:51 INFO - 'SHLVL': '1', 09:56:51 INFO - 'TERM': 'linux', 09:56:51 INFO - 'TMOUT': '86400', 09:56:51 INFO - 'USER': 'cltbld', 09:56:51 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:51 INFO - '_': '/tools/buildbot/bin/python'} 09:56:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:51 INFO - Downloading/unpacking jsonschema 09:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:56:53 INFO - Downloading/unpacking functools32 (from jsonschema) 09:56:53 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:56:53 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:56:53 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:56:53 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:56:53 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:56:53 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:56:53 INFO - Downloading functools32-3.2.3-2.tar.gz 09:56:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 09:56:53 INFO - warning: no files found matching '*.txt' 09:56:53 INFO - no previously-included directories found matching 'build' 09:56:53 INFO - no previously-included directories found matching 'dist' 09:56:53 INFO - no previously-included directories found matching '.git*' 09:56:53 INFO - Installing collected packages: jsonschema, functools32 09:56:53 INFO - Running setup.py install for functools32 09:56:53 INFO - warning: no files found matching '*.txt' 09:56:53 INFO - no previously-included directories found matching 'build' 09:56:53 INFO - no previously-included directories found matching 'dist' 09:56:53 INFO - no previously-included directories found matching '.git*' 09:56:53 INFO - Successfully installed jsonschema functools32 09:56:53 INFO - Cleaning up... 09:56:53 INFO - Return code: 0 09:56:53 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 09:56:53 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:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:53 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:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:53 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 0x7f0c9d597f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bd0290>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, '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': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', '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:56:53 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:56:53 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:56:53 INFO - Using env: {'DISPLAY': ':0', 09:56:53 INFO - 'HOME': '/home/cltbld', 09:56:53 INFO - 'LANG': 'en_US.UTF-8', 09:56:53 INFO - 'LANGUAGE': 'en_US:en', 09:56:53 INFO - 'LOGNAME': 'cltbld', 09:56:53 INFO - 'MAIL': '/var/mail/cltbld', 09:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:53 INFO - 'MOZ_NO_REMOTE': '1', 09:56:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:53 INFO - 'NO_EM_RESTART': '1', 09:56:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:56:53 INFO - 'PWD': '/builds/slave/test-pgo', 09:56:53 INFO - 'SHELL': '/bin/bash', 09:56:53 INFO - 'SHLVL': '1', 09:56:53 INFO - 'TERM': 'linux', 09:56:53 INFO - 'TMOUT': '86400', 09:56:53 INFO - 'USER': 'cltbld', 09:56:53 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:53 INFO - '_': '/tools/buildbot/bin/python'} 09:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:54 INFO - Downloading/unpacking flake8 09:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:56:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:56:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 09:56:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:56:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:56:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:56:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:56:56 INFO - no previously-included directories found matching 'docs/_build' 09:56:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:56:56 INFO - Running setup.py install for pep8 09:56:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:56:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:56:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:56:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:56:56 INFO - no previously-included directories found matching 'docs/_build' 09:56:56 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 09:56:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:56:56 INFO - Cleaning up... 09:56:56 INFO - Return code: 0 09:56:56 INFO - Running post-action listener: _resource_record_post_action 09:56:56 INFO - Running post-action listener: _start_resource_monitoring 09:56:56 INFO - Starting resource monitoring. 09:56:56 INFO - ##### 09:56:56 INFO - ##### Running install step. 09:56:56 INFO - ##### 09:56:56 INFO - Running pre-action listener: _resource_record_pre_action 09:56:56 INFO - Running main action method: install 09:56:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 09:56:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 09:56:57 INFO - Reading from file tmpfile_stdout 09:56:57 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 09:56:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 09:56:57 INFO - Reading from file tmpfile_stdout 09:56:57 INFO - Output received: 09:56:57 INFO - Usage: mozinstall [options] installer 09:56:57 INFO - Options: 09:56:57 INFO - -h, --help show this help message and exit 09:56:57 INFO - -d DEST, --destination=DEST 09:56:57 INFO - Directory to install application into. [default: 09:56:57 INFO - "/builds/slave/test-pgo"] 09:56:57 INFO - --app=APP Application being installed. [default: firefox] 09:56:57 INFO - mkdir: /builds/slave/test-pgo/build/application 09:56:57 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:56:57 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:57:12 INFO - Reading from file tmpfile_stdout 09:57:12 INFO - Output received: 09:57:12 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 09:57:12 INFO - Running post-action listener: _resource_record_post_action 09:57:12 INFO - ##### 09:57:12 INFO - ##### Running run-tests step. 09:57:12 INFO - ##### 09:57:12 INFO - Running pre-action listener: _resource_record_pre_action 09:57:12 INFO - Running main action method: run_tests 09:57:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 09:57:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 09:57:12 INFO - Python 2.7.3 09:57:12 INFO - Return code: 0 09:57:12 INFO - grabbing minidump binary from tooltool 09:57:12 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:57:12 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 0x2c62850>, '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 0x2bce330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bce7c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 09:57:12 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:57:12 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:57:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:57:12 INFO - Return code: 0 09:57:12 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 09:57:12 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 09:57:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 09:57:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 09:57:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 09:57:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 09:57:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 09:57:13 INFO - Return code: 0 09:57:13 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/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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:57:13 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/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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:57:13 INFO - Using env: {'DISPLAY': ':0', 09:57:13 INFO - 'HOME': '/home/cltbld', 09:57:13 INFO - 'LANG': 'en_US.UTF-8', 09:57:13 INFO - 'LANGUAGE': 'en_US:en', 09:57:13 INFO - 'LOGNAME': 'cltbld', 09:57:13 INFO - 'MAIL': '/var/mail/cltbld', 09:57:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:57:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 09:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:13 INFO - 'MOZ_NO_REMOTE': '1', 09:57:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 09:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:13 INFO - 'NO_EM_RESTART': '1', 09:57:13 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 09:57:13 INFO - 'PWD': '/builds/slave/test-pgo', 09:57:13 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 09:57:13 INFO - 'SHELL': '/bin/bash', 09:57:13 INFO - 'SHLVL': '1', 09:57:13 INFO - 'TERM': 'linux', 09:57:13 INFO - 'TMOUT': '86400', 09:57:13 INFO - 'USER': 'cltbld', 09:57:13 INFO - 'XDG_SESSION_COOKIE': '4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379', 09:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:13 INFO - '_': '/tools/buildbot/bin/python'} 09:57:13 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/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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:57:13 INFO - mozversion application_buildid: 20160304073420 09:57:13 INFO - mozversion application_changeset: 38bc572c312206a89c09b6c280840970ff12fc5f 09:57:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:57:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:57:13 INFO - mozversion application_name: Firefox 09:57:13 INFO - mozversion application_remotingname: firefox-dev 09:57:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:57:13 INFO - mozversion application_vendor: Mozilla 09:57:13 INFO - mozversion application_version: 46.0a2 09:57:13 INFO - mozversion platform_buildid: 20160304073420 09:57:13 INFO - mozversion platform_changeset: 38bc572c312206a89c09b6c280840970ff12fc5f 09:57:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:57:13 INFO - mozversion platform_version: 46.0a2 09:57:13 INFO - using testdate: 1457114233 09:57:13 INFO - actual date: 1457114233 09:57:13 INFO - starting webserver on 'localhost:50667' 09:57:13 INFO - SUITE-START | Running 3 tests 09:57:13 INFO - TEST-START | dromaeo_css 09:57:13 INFO - operating with platform_type : linux_ 09:57:13 INFO - Initialising browser for dromaeo_css test... 09:57:13 INFO - TEST-INFO | started process 2656 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVIW2GB/profile http://localhost:50667/getInfo.html) 09:57:16 INFO - PROCESS | 2656 | 09:57:16 INFO - PROCESS | 2656 | (process:2804): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:57:17 INFO - PROCESS | 2656 | __metrics Screen width/height:1600/1200 09:57:17 INFO - PROCESS | 2656 | colorDepth:24 09:57:17 INFO - PROCESS | 2656 | Browser inner width/height: 1024/697 09:57:17 INFO - PROCESS | 2656 | __metrics 09:57:18 INFO - TEST-INFO | 2656: exit 0 09:57:18 INFO - Browser initialized. 09:57:18 INFO - Running cycle 1/1 for dromaeo_css test... 09:57:18 INFO - TEST-INFO | started process 2879 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpVIW2GB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:20 INFO - PROCESS | 2879 | 09:57:20 INFO - PROCESS | 2879 | (process:2945): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:57:30 INFO - PROCESS | 2879 | RSS: Main: 141348864 09:57:30 INFO - PROCESS | 2879 | 09:58:20 INFO - PROCESS | 2879 | 1457114300164 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] 10:00:28 INFO - PROCESS | 2879 | Cycle 1(1): loaded http://localhost:50667/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50667/tests/dromaeo/cssquery-ext.html) 10:00:29 INFO - PROCESS | 2879 | RSS: Main: 146042880 10:00:29 INFO - PROCESS | 2879 | 10:03:31 INFO - PROCESS | 2879 | Cycle 1(1): loaded http://localhost:50667/tests/dromaeo/cssquery-ext.html (next: http://localhost:50667/tests/dromaeo/cssquery-jquery.html) 10:03:32 INFO - PROCESS | 2879 | RSS: Main: 145186816 10:03:32 INFO - PROCESS | 2879 | 10:06:33 INFO - PROCESS | 2879 | Cycle 1(1): loaded http://localhost:50667/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50667/tests/dromaeo/cssquery-mootools.html) 10:06:34 INFO - PROCESS | 2879 | RSS: Main: 143974400 10:06:34 INFO - PROCESS | 2879 | 10:09:35 INFO - PROCESS | 2879 | Cycle 1(1): loaded http://localhost:50667/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50667/tests/dromaeo/cssquery-prototype.html) 10:09:36 INFO - PROCESS | 2879 | RSS: Main: 143544320 10:09:36 INFO - PROCESS | 2879 | 10:12:38 INFO - PROCESS | 2879 | Cycle 1(1): loaded http://localhost:50667/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50667/tests/dromaeo/cssquery-yui.html) 10:12:39 INFO - PROCESS | 2879 | RSS: Main: 145305600 10:12:39 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | Cycle 1(1): loaded http://localhost:50667/tests/dromaeo/cssquery-yui.html (next: http://localhost:50667/tests/dromaeo/cssquery-dojo.html) 10:15:41 INFO - PROCESS | 2879 | RSS: Main: 147263488 10:15:41 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | __start_tp_report 10:15:41 INFO - PROCESS | 2879 | _x_x_mozilla_page_load 10:15:41 INFO - PROCESS | 2879 | _x_x_mozilla_page_load_details 10:15:41 INFO - PROCESS | 2879 | |i|pagename|runs| 10:15:41 INFO - PROCESS | 2879 | |0;dojo.html;3293.637699853014;3301.141703157179;3308.768386212966;3338.666133386661;3339.3154403347316;5364.5708343332535;5371.382291036527;5380.085385484467;5394.1908713692965;5407.405185429609;5383.138697808351;5401.378841433237;5402.729863506819;5407.242985981956;5429.429909859467;4909.680870743408;4954.504549545035;4987.8254261100865;5000.97499512502;5069.290299358093;4831.420229572456;4956.603471722254;4986;4991.550760431561;5118.232265160237;4971.701697898119;5011.223260394627;5025.447200807908;5029.094762942669;5033.194688849785;4914.533119353656;5004.449510553836;5010.298558201855;5013.473585273548;5019.096562618727;4872.4640289568115;4879.438864530589;4942.752862356903;4955.4796746341835;4956.1822299320565;5171.172612382038;5198.350206224222;5208.8437346879655;5218.765155568009;5229.555487783543;5737.655740655574;5746.195532625436;5756.453136951983;5768.105943578752;5769.394213607595;5737;5750.079987202028;5772.826815195551;5822.592418530705;5831.970840145815;5412.702301373423;5452.536534394581;5462.9726851365485;5463.153211252223;5481.205225242357;5889.352171261158;5901.911471327933;5905.970470147622;5909.6158749681135;5921.496672782776;5568.16477528374;5589.888202235932;5643.5767317451355;5649.265595472644;5748.051571490741;5746.965546201724;5747.942520574741;5756.194132821408;5757.309122905278;5842.474177324061;5721.599488035881;5726.141078838207;5726.627769194989;5730.08318669011;5823.475887170175;5685.374608793029;5701.2018317435595;5770.105633626795;5830.591858569859;5849.736761845727;4418.624416924597;4419.712718673276;4428.092241090569;4436.733795972252;4448.621867141264;3978.8607398740905;3984.7808370539897;4003.4195041718785;4007.6393124618926;4021.2158629067635;5390.380106287748;5413.756380962866;5484.122540393518;5509.035918714289;5513.669179849222;5844.1233814927255;5872.559558033165;5881.7647294107755;5883.705814709333;5884.264466941632;5481.013417584793;5513.393526712059;5535.391106978229;5543.805966791143;5555.083411237181;4953.975230123826;4984.675996060245;5034.043531728959;5038.294638750578;5051;5849.853753656193;5864.589478736533;5872;5895.263092113486;5896.1450589664255;5779.653220806765;5824.242848429677;5828.358880523139;5834.591578589628;5843.970780146242;5708.115242137475;5781.364049954585;5782.653040817564;5794.449527294901;5905.202797622266;5466.972665136649;5493.038718224215;5501.0098182327565;5502.697351645698;5522.420145884625;5786.42135786418;5832;5866.061430171152;5868.5305175585;5869.559783016205;5585.497305242548;5655.123455864347;5710.229119068873;5710.543156547383;5734.512566431733;5613.971930140323;5654.264945557051;5682.14767784819;5684.7441865117;5689.715514224189;5588.105903055654;5589.385167631639;5592.328920529562;5594.356648985256;5600.215969764317;5583.525400341016;5610.971945140248;5703.372629010722;5718.228039214653;5821.738021788945;5511.779528818803;5531.502164805026;5559.138333558305;5576.470235327637;5581.386047534849 10:15:41 INFO - PROCESS | 2879 | |1;ext.html;555.8026900450342;569.0014025385448;574.207593520941;598.7066337494628;661.7650733989434;40329;40890.7955460223;41308.793456032756;41397.9650508737;41463;45557;46397;46501.30248046282;46837.06325873481;46884.296735549;8833.46999180048;9146.451212927212;9169.54152292386;9170.954145229265;9180.357374983754;8823.823523529525;8968.64125434985;8993.055729148444;9010.459372437643;9046.095390460967;8966.775830604221;9059.411138275991;9086.04596517366;9108.271338292954;9140;8940.15068568485;8970.641174353019;8973.371863969525;9007.594658240396;9019.188273055424;8913.331500137516;8959.148880856308;8965.910340896638;9003.549822508914;9008.324375671851;42382.78808605968;42500.1499970002;42808.78595607033;42825.14349713019;42856.357154642705;3756.1736417988272;3803.315403227418;3841.135744457503;3904.707146963989;4049.3925911113274;28827.42345153106;28948;29168.124956251348;33002.504962425584;33448.6655133448;4320.589543993316;4333.263345231287;4336.8698939031565;4365.9126817463475;4371.628411585019;295815;302371;303500;304511;304515.4774226137;45629.54370456253;46134.538654613694;46231;46458.535414646096;67476;44714.55285447169;44759.77620111879;45199.77400113044;45259.54740452553;45300;16392.836071639133;16519.174041297887;16574.502764917073;16575.6684866302;16616.169191540375;13245.072844900957;13314.933425333005;13455;13468.730625387438;13669.384877680528;15442.382304707686;15468;15635.452759153375;15650.921745391428;15688.843111568738;6063.272407311151;6160.168377269101;6188.4739797117745;6219.906701399483;6282;1076.1767248055244;1102.9834552481718;1145.6792098212513;1164.6564263542275;1241.6652098993618;30836;31017.534736978963;31064.68935310618;31369.215769605944;31789;1159.1248607301602;1198.0176255470606;1215.5502464088345;1226.1723336747839;1235.178606226849;16059.839401605834;17320.30718771235;17785.555361116076;17793.911030445022;18064.18711158001;532724;533185;534986;537380;823354;56780.148297775566;57410;57433.71283143557;57535.42464575385;57578;1493.148905124069;1513.9772903406458;1523.5962469945473;1526.282647155835;1527.4577524978695;30237.697623023487;31233;31601;31916.840415798702;34032.65967340394;51757.482425175265;51895.2215716757;52344.47655523396;52431.475685244186;52475.47524524706;335927;336260;338447;338673;341149;35131.121721957155;35297.64702352943;35490.46764298486;35668.643313566536;47917.76041119772;46709;46995.53004469912;47024;47072.529274706816;47870;38992.83021509359;39105.6089439102;39365;39670.80164599274;39957.2008559833;39522.20955580815;40718.796406017784;41264.587354127274;42084.36873446962;43851.34222986594;38495.230095397375;38744;43702.34446483369;43880.78059609682;43902.34146487741;33443.33113337671;35269.726127313756;35757.46363804597;35824.46263306;36005.63994360023;27002.189934302;27691;27887.884484620387;28150.85924570364;28200.29499262535 10:15:41 INFO - PROCESS | 2879 | |2;jquery.html;392.07274795109566;396.90474286171326;398.37256321293967;401.25767330438686;402.9516458025037;15086.3211155498;15395.307211175495;17039.488815335546;17569.56076098098;17664.64670706585;16318.918405407956;16893.408730694428;17245.74131388027;17525;17721.113944302797;5220.817271395502;5430.91853622196;5436.184572314165;5436.21174929635;5459.727013649302;5341.839744807662;5396.10964190908;5580.944190558103;5603.691796951166;5609.1305847593485;5297.576193904498;5540.9168862467095;5543.029969755296;5584.581156413265;5593.356763972155;5373.220882971987;5494.780208791663;5503.724813759316;5746.683932383718;5791.65250084992;5614.410486898899;5668.829935101953;5679.7160141993145;5684.34630017545;5687.061634830248;14302.570922872226;14348.282585870666;19127.617447651108;20238.898805506025;21484.462888427915;2068.4415207893935;2178.7494438139656;2227.3429338345227;2298.1152256381247;2362.362162216192;10357.844632330522;10703.83944240837;10893.455327233607;11543.711407214803;13844.93077534616;1810.4568629411256;1874.2034635280088;1930.3629802165392;1939.2533874458295;1979.901004949761;58225;59528;60589;61456.69271653658;61669;10661.413622250695;10868.891311086787;11136.38749868765;11146.498407571678;11254.15593830466;14818.62953426173;14850.554483365517;14869.479568215276;14900.776488352683;14996.62508437276;8259.463134896212;8423.957880210683;8598.140185981478;8612.5693715314;8644.524551149621;8400.579971001425;8448.11294814048;8451.197136272045;8462.830743385099;8526.658933642584;4628.67599268048;4749.430068391815;4825.662203645781;4829.333879811459;4883.755812209375;1950.1224448998007;1958.8432925365937;1992.3026940570974;1997.3408775104472;2001.6388855578055;996.785691076422;1147.690123666622;1168.7896178687747;1183.1540448579308;1185.9229150105216;15645.295961681752;16044.197790110682;17102.74345884788;17199;17835.10824458772;1035.0736091198335;1041.95832166714;1068.1988508618535;1103.5971870267456;1139.0830381542771;9647;9800.460974646463;9870.16103631185;9892.901070989343;9963.900360996297;449130;450219;455891;696604;712592;76562;78898.21101789024;79750.60124699339;80348.68843563218;82352.58823705843;1187.1155988788234;1214.9028077753758;1227.5396726227486;1230.366361323919;1230.5139469909336;16175.757363639319;16191.595210119842;16212.675746484769;16261.918690406472;16489.175541222652;33630;34092;34577.65422345845;35835.64164358323;35971.82014089913;45428.77285613551;46065.30902036405;48073.75963120162;49294.75352623214;49554;7428.368588670023;7585;7807.843843123219;7817.648205830865;8024.438289319692;10840.566377344818;11464.885351146382;11503.252288601214;11563.6530904073;12144.149909506603;10226.948865255627;10250.282480226611;10325.122364599094;10436.956304369502;10778.622748203776;9630.759231019281;9942.801143977225;10020.949895250476;10171.13545348624;10851.969062939008;10006.723609831528;10463.215258855464;10516.842247366143;10904.182186335898;11056.557737690724;7947.642356093874;8089.110197878346;8090.393220508367;8341.791455213668;8350.373721970755;10635.787284254426;10774.353538787698;10799.514021869192;10893.727656808644;11006.064484518905 10:15:41 INFO - PROCESS | 2879 | |3;mootools.html;1535.224711520682;1544.5289186798025;1749.3177660712324;1842.9907850460745;1880.210311669098;8307.50154990701;8311.584420778967;8493.617787199575;8495.490270583769;8508.574571271427;8507.532085735282;8525.91474085259;8581.09898460662;8607.311415086793;8617.095205003463;2494.2267896951957;2518.3704073981503;2524.0787112703915;2529.152733834168;2537.5051864886354;2432.683751112353;2488.8880000399936;2489.7634724701124;2498.850068995857;2499.800015998725;2150.3656421355736;2203.7025001624734;2411.1560953666253;2478.293686299405;2479.1323036937106;2411.5538625354366;2436.2082323245018;2436.451798345367;2450.0444826517673;2452.6688896998853;2392.198613464474;2435.0625009371342;2443.731189569146;2444.8044156467618;2451.3381387025584;8374.581270936429;8378.329733621296;8381.83236335276;8467.70363037297;8480.66077356905;5043.924341134886;5227.398849132361;5242.2136679498;5243.632945693803;5247;3228.4027454920915;3338.549295845054;3376.9493457598155;3388.0513456232047;3417.094469965461;1136.4658610453073;1149.4425203776161;1151.7984352738322;1195.7010747313172;1209.1596340543326;11444.656660300205;11567.942160289229;11599.130065245055;11605.071594272438;11608.593699220572;4542.136993971135;4601.401817763669;4623.375844261049;4633.559811817872;4641.8375356863025;9527.618895244113;9537.570809313598;9543.093406126407;9566.18687411564;9586.712398628053;5062.265971434122;5081;5084.77118529667;5101.642884998088;5114.565261952701;4566.246569316092;4589.655775816827;4600.148972440109;4657.184992626277;4671.11248862715;3762.4168253920684;3779.8110094495164;3786.1859700164596;3843.0584506795453;3864.9613503864603;1071.239420011785;1083.0360978728938;1085.5006696919506;1086.5545126498098;1090.9345439273668;1048.7535429174138;1101.1906248907046;1158.6813626252679;1202.5610652111916;1390.847006829248;7826.686932522749;7904.170062143392;7907.327877130509;7918.841623167505;7931.524108553505;830.9916900831035;1295.5271325965955;1297.4356155072574;1306.1510018487868;1320.544412177797;5976.9402305977255;5987.31145918225;6026.758929642765;6041.184440100618;6049.274087109575;9709.174720148867;10279.94860025695;10358.119559837347;10370.585176593002;10370.96290370957;10185.33795303303;10220.335678180894;10261.74345641365;10314.896851031544;10412.739681508023;7740.961295193488;7875.448718589759;7886.72396466121;7912.920870791334;7974.322182614543;7999.160088190656;8049.83900322002;8113.269805717514;8127.390445716713;8257.38069644767;9090.272778177598;9222.95388523053;9368.110029547182;9409.48247846375;9489.240860730986;9459.10138536838;9482.810343793224;9592.95203523978;9653.03469653029;9819.263555233243;7607.27730865589;7642.159362470013;7715.498492597963;7726.18875018115;7738.342240909586;8081.959590202011;8218.958905205436;8229.05365882931;8272.131426200162;8274.13121622221;7607.087149542091;7853.528788272722;7858.960705196666;7925.28672419485;7936.682532698627;6997.965010174917;7003.8249043772275;7066.364027237573;7449.851002979802;7945.48354356965;7633.0458692663415;7681.654325555475;7701.537907725555;7706.229377062249;7715.267049630276;6952.096227490394;7023.086998690342;7041.401480874183;7053.329933656294;7080.256573059754;6731.293214212633;6744.359285867835;6749.055132281386;6771.3905748482875;6897.241303456515 10:15:41 INFO - PROCESS | 2879 | |4;prototype.html;345.39383382164306;356.15768707007936;360.32978659692964;361.81366596202975;365.1145971020277;6422.100905873176;6444.194475690539;6460.7092680829355;6484.773032943848;6578.243501997272;6441.7423303067935;6466.902996455057;6469.094326794241;6479.86602344589;6486.318936511669;4919.065377578248;4922.286268491067;4922.753862306889;4943.876403089925;4953.826616068438;4965.3048573199785;4970.304157417964;4972.154733695263;4978.2532620107095;4979.7261150636705;4818.373611430509;4925.876853078666;4928.33467481889;4931.161702510564;4945.554900058994;4839.588634966032;4858.29554714568;4876.414830220361;4876.658633895611;4880.121578115938;4274.102438487921;4288.978555107205;4291.742495450252;4302.41917341158;4308.870733877989;5987.311459182162;6109;6113.908291375633;6129.785457508966;6133.846653833645;5775.451332123442;5782.855428614276;5784.797532086357;5785.190073389729;5789.8263052108505;6834.692438840264;6865.5537390069485;6870.20992585854;6877.862442751167;6885;4761.547652972962;4767.022760334158;4773.928391074137;4774.379330686988;4787.8563643090765;6920.688569014406;6994.545354551989;7014.298570142996;7083.327083927019;7085.787426377217;4846.5638092571835;4860.513948605111;4861.927071093936;4866.732329721899;4874.268859671007;4910.852674419773;4928.605711543068;4929.926051109236;4936.111499929975;4940.135476291706;5498.477644623755;5693.800716974886;5697.829065127969;5709.857253568611;5711.857203569944;6814.420774234246;6821;6833.214180369222;6856.725730970706;6895.862082758417;1705.249690136336;1728.0150314320717;1738.7304967730022;1750.3348727483474;1751.5621094726318;1166.125405945541;1174.4304012553905;1180.9468573914194;1189.8096304591397;1190.9642710718692;6481.09264702942;6501.967490162613;6506.154199953976;6515.967420162869;6539.574927629689;6917;6975.441964642816;6982.546134501241;6990.895136572955;6991.7902462926195;6399.392057754506;6479.6760161991715;6490.675466226669;6492.156019717501;6498.870022599522;3784.7729136251914;3794.449804778347;3807;3808.6953043756434;3810.314143454205;7073.681684324218;7083.150021997393;7106.2183159852375;7106.92893071073;7116.679749411289;6926.272741362084;7093.290670932865;7129.108861392326;7171.462140339496;7193.172785129673;7091.716331346688;7093.964530177316;7105;7116.928830711627;7121.394681452135;6934.618595977269;6987.5108742389575;6987.7204911802955;6989.33601307875;6990.322322642631;6637.469002479694;6882.380585747307;6884.070650462324;6891.827704307232;6915.792526224222;7051.330123638246;7063.682134304071;7116.50184487081;7117.75087871922;7118.9644051779405;4894.535019173173;4894.681845680019;4895.877603059809;4901.90196196067;4906.950930490792;6828.9658551706925;6976.19773726028;6978.3370579796865;6980.092587963532;6990.755323563651;6755.729770809113;6774.288699686462;6782.423494002867;6793.558418702769;6795.354441328263;6694.464442844658;6733.393994540319;6765.763198288037;6769.796906092825;6773.424258938046;6471.191101112361;6597.505187111082;6632.204135503771;6640.667966601747;6643.936970084763;6748.223954245324;6750.763723269662;6759.425448836904;6769.458443324621;6770.898436523555;6535.934640653532;6577.835554111186;6618.338166183343;6636.336366363518;6658.267590564937 10:15:41 INFO - PROCESS | 2879 | |5;yui.html;852.560931120473;861.4917198852676;923.7505873414178;944.0889541592363;949.2785483032895;14523.41906323747;14607.707845843077;14666.926665366685;14726.705465890676;14737.115773053625;13492.932535337333;13502.864971350284;13552.457901683945;13553.728925421485;13565.728685426286;863.3567991846083;870.0864092702637;898.7663207566047;899.9100089991014;902.6073657958809;901.7204666553389;910.308165793998;914.6981496106303;916.4043371808311;919.8988111307751;949.463553092504;954.4607296877242;955.8183945050453;958.018031517693;958.8753462049924;936.6581197862765;941.4680705401427;942.4251206763869;951.3911096898025;952.2572393533055;698.5110422704076;700.1702981966336;700.663681432915;703.0965209705504;703.2721133626723;14555.854441455556;14556.344964476624;14574.615411535888;14605.12369257848;14857;13440.5295814647;13452;13463.730725385438;13805.861941380557;14173.71652566943;11198.832017519662;11383.03244224242;11553.422328883522;11620.76758464826;11657.242279251906;3988.5213774346976;3998.6401223889698;4000.819963101638;4075.246079475277;4076.2458945095254;50745.49254507445;52942.73528632371;53912;53992.73003634957;54172.187417188776;10935.83596246057;10990.670279891616;11124.165687573464;11157.051650609788;11276.32342059479;19453;19567.706484402745;19638.410847674593;19639.214431422584;19664.803351966297;8008.399370047153;8311.33509319253;8350.582470876432;8372.99524057117;8638.697645582375;4516.322551617218;4646.883827904329;4718.4337879454;4814.350062741555;4867.123917694778;3209;3230.3377807549396;3235.498497732855;3239.611246650417;3245.026492052375;574.9587130711371;616.3589866538839;616.4759954039031;619.0250355689807;620.7889317632026;645.8740545593564;662.7647185249264;726.691156258588;730.5251586468731;743.5241445474901;9034.86447703285;9812.411255324703;9869.753256168653;9923.851142232872;10015.649452269136;588.8851673923543;667.5093806052547;702.8980797784293;725.0284618610981;726.8655298769639;4331.935020974688;4624.630029597624;4702.741349225757;4728.503507131771;4783.23468245086;55226.44773552213;55954;62909.68545157245;63019;63323.36676633267;52754;53090.20364694455;53143.46856531385;53196.46803531992;54345.72827135918;49919.75040124776;50359.49640503621;50372.99254014899;50556;50635.746821266395;9457.290703197425;9568.95215523918;9722.173615242784;9913.900860991298;9950.154236889946;35162.648373515935;35725.74959876392;36574;36585;36606;45393.77303113463;45896.31155532603;45952.31071533863;46567;47157.52842471531;10405.791884162425;11669.76660466769;12349.259044457362;12445.439955201857;12831.550895718605;13498.460061597427;13584.184948903097;13668.589942301747;14000.299985000505;14052.156870587798;12014.63956081319;12071.637850864488;12137.69655758613;12641.115121941375;12715.682107947005;11829.349385783864;12069.939650302043;12353.135280530276;12712.555060572835;12828.935855320664;11399.259048161843;11597.826032609686;11888.167828252286;12499.500019999461;12771.425285861973;10044.196464282986;10205.183585313305;10366.067053964878;10664.360138391721;10920.126389888948;10416.531256093342;10974.561017559208;11105.555777768799;11147.275427097213;11209.215354925404 10:15:41 INFO - PROCESS | 2879 | __end_tp_report 10:15:41 INFO - PROCESS | 2879 | __start_cc_report 10:15:41 INFO - PROCESS | 2879 | _x_x_mozilla_cycle_collect,297 10:15:41 INFO - PROCESS | 2879 | __end_cc_report 10:15:41 INFO - PROCESS | 2879 | __startTimestamp1457115341823__endTimestamp 10:15:41 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | ------- Summary: start ------- 10:15:41 INFO - PROCESS | 2879 | Number of tests: 6 10:15:41 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | [#0] dojo.html Cycles:165 Average:5373.76 Median:5559.14 stddev:569.57 (10.2%) stddev-sans-first:547.44 10:15:41 INFO - PROCESS | 2879 | Values: 3293.6 3301.1 3308.8 3338.7 3339.3 5364.6 5371.4 5380.1 5394.2 5407.4 5383.1 5401.4 5402.7 5407.2 5429.4 4909.7 4954.5 4987.8 5001.0 5069.3 4831.4 4956.6 4986.0 4991.6 5118.2 4971.7 5011.2 5025.4 5029.1 5033.2 4914.5 5004.4 5010.3 5013.5 5019.1 4872.5 4879.4 4942.8 4955.5 4956.2 5171.2 5198.4 5208.8 5218.8 5229.6 5737.7 5746.2 5756.5 5768.1 5769.4 5737.0 5750.1 5772.8 5822.6 5832.0 5412.7 5452.5 5463.0 5463.2 5481.2 5889.4 5901.9 5906.0 5909.6 5921.5 5568.2 5589.9 5643.6 5649.3 5748.1 5747.0 5747.9 5756.2 5757.3 5842.5 5721.6 5726.1 5726.6 5730.1 5823.5 5685.4 5701.2 5770.1 5830.6 5849.7 4418.6 4419.7 4428.1 4436.7 4448.6 3978.9 3984.8 4003.4 4007.6 4021.2 5390.4 5413.8 5484.1 5509.0 5513.7 5844.1 5872.6 5881.8 5883.7 5884.3 5481.0 5513.4 5535.4 5543.8 5555.1 4954.0 4984.7 5034.0 5038.3 5051.0 5849.9 5864.6 5872.0 5895.3 5896.1 5779.7 5824.2 5828.4 5834.6 5844.0 5708.1 5781.4 5782.7 5794.4 5905.2 5467.0 5493.0 5501.0 5502.7 5522.4 5786.4 5832.0 5866.1 5868.5 5869.6 5585.5 5655.1 5710.2 5710.5 5734.5 5614.0 5654.3 5682.1 5684.7 5689.7 5588.1 5589.4 5592.3 5594.4 5600.2 5583.5 5611.0 5703.4 5718.2 5821.7 5511.8 5531.5 5559.1 5576.5 5581.4 10:15:41 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | [#1] ext.html Cycles:180 Average:57195.62 Median:41286.69 stddev:116213.47 (281.5%) stddev-sans-first:116461.67 10:15:41 INFO - PROCESS | 2879 | Values: 555.8 569.0 574.2 598.7 661.8 40329.0 40890.8 41308.8 41398.0 41463.0 45557.0 46397.0 46501.3 46837.1 46884.3 8833.5 9146.5 9169.5 9171.0 9180.4 8823.8 8968.6 8993.1 9010.5 9046.1 8966.8 9059.4 9086.0 9108.3 9140.0 8940.2 8970.6 8973.4 9007.6 9019.2 8913.3 8959.1 8965.9 9003.5 9008.3 42382.8 42500.1 42808.8 42825.1 42856.4 3756.2 3803.3 3841.1 3904.7 4049.4 28827.4 28948.0 29168.1 33002.5 33448.7 4320.6 4333.3 4336.9 4365.9 4371.6 295815.0 302371.0 303500.0 304511.0 304515.5 45629.5 46134.5 46231.0 46458.5 67476.0 44714.6 44759.8 45199.8 45259.5 45300.0 16392.8 16519.2 16574.5 16575.7 16616.2 13245.1 13314.9 13455.0 13468.7 13669.4 15442.4 15468.0 15635.5 15650.9 15688.8 6063.3 6160.2 6188.5 6219.9 6282.0 1076.2 1103.0 1145.7 1164.7 1241.7 30836.0 31017.5 31064.7 31369.2 31789.0 1159.1 1198.0 1215.6 1226.2 1235.2 16059.8 17320.3 17785.6 17793.9 18064.2 532724.0 533185.0 534986.0 537380.0 823354.0 56780.1 57410.0 57433.7 57535.4 57578.0 1493.1 1514.0 1523.6 1526.3 1527.5 30237.7 31233.0 31601.0 31916.8 34032.7 51757.5 51895.2 52344.5 52431.5 52475.5 335927.0 336260.0 338447.0 338673.0 341149.0 35131.1 35297.6 35490.5 35668.6 47917.8 46709.0 46995.5 47024.0 47072.5 47870.0 38992.8 39105.6 39365.0 39670.8 39957.2 39522.2 40718.8 41264.6 42084.4 43851.3 38495.2 38744.0 43702.3 43880.8 43902.3 33443.3 35269.7 35757.5 35824.5 36005.6 27002.2 27691.0 27887.9 28150.9 28200.3 10:15:41 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | [#2] jquery.html Cycles:180 Average:28771.99 Median:2330.24 stddev:92720.37 (3979.0%) stddev-sans-first:92955.98 10:15:41 INFO - PROCESS | 2879 | Values: 392.1 396.9 398.4 401.3 403.0 15086.3 15395.3 17039.5 17569.6 17664.6 16318.9 16893.4 17245.7 17525.0 17721.1 5220.8 5430.9 5436.2 5436.2 5459.7 5341.8 5396.1 5580.9 5603.7 5609.1 5297.6 5540.9 5543.0 5584.6 5593.4 5373.2 5494.8 5503.7 5746.7 5791.7 5614.4 5668.8 5679.7 5684.3 5687.1 14302.6 14348.3 19127.6 20238.9 21484.5 2068.4 2178.7 2227.3 2298.1 2362.4 10357.8 10703.8 10893.5 11543.7 13844.9 1810.5 1874.2 1930.4 1939.3 1979.9 58225.0 59528.0 60589.0 61456.7 61669.0 10661.4 10868.9 11136.4 11146.5 11254.2 14818.6 14850.6 14869.5 14900.8 14996.6 8259.5 8424.0 8598.1 8612.6 8644.5 8400.6 8448.1 8451.2 8462.8 8526.7 4628.7 4749.4 4825.7 4829.3 4883.8 1950.1 1958.8 1992.3 1997.3 2001.6 996.8 1147.7 1168.8 1183.2 1185.9 15645.3 16044.2 17102.7 17199.0 17835.1 1035.1 1042.0 1068.2 1103.6 1139.1 9647.0 9800.5 9870.2 9892.9 9963.9 449130.0 450219.0 455891.0 696604.0 712592.0 76562.0 78898.2 79750.6 80348.7 82352.6 1187.1 1214.9 1227.5 1230.4 1230.5 16175.8 16191.6 16212.7 16261.9 16489.2 33630.0 34092.0 34577.7 35835.6 35971.8 45428.8 46065.3 48073.8 49294.8 49554.0 7428.4 7585.0 7807.8 7817.6 8024.4 10840.6 11464.9 11503.3 11563.7 12144.1 10226.9 10250.3 10325.1 10437.0 10778.6 9630.8 9942.8 10020.9 10171.1 10852.0 10006.7 10463.2 10516.8 10904.2 11056.6 7947.6 8089.1 8090.4 8341.8 8350.4 10635.8 10774.4 10799.5 10893.7 11006.1 10:15:41 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | [#3] mootools.html Cycles:180 Average:5933.62 Median:5242.92 stddev:3098.00 (59.1%) stddev-sans-first:3089.05 10:15:41 INFO - PROCESS | 2879 | Values: 1535.2 1544.5 1749.3 1843.0 1880.2 8307.5 8311.6 8493.6 8495.5 8508.6 8507.5 8525.9 8581.1 8607.3 8617.1 2494.2 2518.4 2524.1 2529.2 2537.5 2432.7 2488.9 2489.8 2498.9 2499.8 2150.4 2203.7 2411.2 2478.3 2479.1 2411.6 2436.2 2436.5 2450.0 2452.7 2392.2 2435.1 2443.7 2444.8 2451.3 8374.6 8378.3 8381.8 8467.7 8480.7 5043.9 5227.4 5242.2 5243.6 5247.0 3228.4 3338.5 3376.9 3388.1 3417.1 1136.5 1149.4 1151.8 1195.7 1209.2 11444.7 11567.9 11599.1 11605.1 11608.6 4542.1 4601.4 4623.4 4633.6 4641.8 9527.6 9537.6 9543.1 9566.2 9586.7 5062.3 5081.0 5084.8 5101.6 5114.6 4566.2 4589.7 4600.1 4657.2 4671.1 3762.4 3779.8 3786.2 3843.1 3865.0 1071.2 1083.0 1085.5 1086.6 1090.9 1048.8 1101.2 1158.7 1202.6 1390.8 7826.7 7904.2 7907.3 7918.8 7931.5 831.0 1295.5 1297.4 1306.2 1320.5 5976.9 5987.3 6026.8 6041.2 6049.3 9709.2 10279.9 10358.1 10370.6 10371.0 10185.3 10220.3 10261.7 10314.9 10412.7 7741.0 7875.4 7886.7 7912.9 7974.3 7999.2 8049.8 8113.3 8127.4 8257.4 9090.3 9223.0 9368.1 9409.5 9489.2 9459.1 9482.8 9593.0 9653.0 9819.3 7607.3 7642.2 7715.5 7726.2 7738.3 8082.0 8219.0 8229.1 8272.1 8274.1 7607.1 7853.5 7859.0 7925.3 7936.7 6998.0 7003.8 7066.4 7449.9 7945.5 7633.0 7681.7 7701.5 7706.2 7715.3 6952.1 7023.1 7041.4 7053.3 7080.3 6731.3 6744.4 6749.1 6771.4 6897.2 10:15:41 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | [#4] prototype.html Cycles:180 Average:5676.19 Median:6482.93 stddev:1684.11 (26.0%) stddev-sans-first:1640.62 10:15:41 INFO - PROCESS | 2879 | Values: 345.4 356.2 360.3 361.8 365.1 6422.1 6444.2 6460.7 6484.8 6578.2 6441.7 6466.9 6469.1 6479.9 6486.3 4919.1 4922.3 4922.8 4943.9 4953.8 4965.3 4970.3 4972.2 4978.3 4979.7 4818.4 4925.9 4928.3 4931.2 4945.6 4839.6 4858.3 4876.4 4876.7 4880.1 4274.1 4289.0 4291.7 4302.4 4308.9 5987.3 6109.0 6113.9 6129.8 6133.8 5775.5 5782.9 5784.8 5785.2 5789.8 6834.7 6865.6 6870.2 6877.9 6885.0 4761.5 4767.0 4773.9 4774.4 4787.9 6920.7 6994.5 7014.3 7083.3 7085.8 4846.6 4860.5 4861.9 4866.7 4874.3 4910.9 4928.6 4929.9 4936.1 4940.1 5498.5 5693.8 5697.8 5709.9 5711.9 6814.4 6821.0 6833.2 6856.7 6895.9 1705.2 1728.0 1738.7 1750.3 1751.6 1166.1 1174.4 1180.9 1189.8 1191.0 6481.1 6502.0 6506.2 6516.0 6539.6 6917.0 6975.4 6982.5 6990.9 6991.8 6399.4 6479.7 6490.7 6492.2 6498.9 3784.8 3794.4 3807.0 3808.7 3810.3 7073.7 7083.2 7106.2 7106.9 7116.7 6926.3 7093.3 7129.1 7171.5 7193.2 7091.7 7094.0 7105.0 7116.9 7121.4 6934.6 6987.5 6987.7 6989.3 6990.3 6637.5 6882.4 6884.1 6891.8 6915.8 7051.3 7063.7 7116.5 7117.8 7119.0 4894.5 4894.7 4895.9 4901.9 4907.0 6829.0 6976.2 6978.3 6980.1 6990.8 6755.7 6774.3 6782.4 6793.6 6795.4 6694.5 6733.4 6765.8 6769.8 6773.4 6471.2 6597.5 6632.2 6640.7 6643.9 6748.2 6750.8 6759.4 6769.5 6770.9 6535.9 6577.8 6618.3 6636.3 6658.3 10:15:41 INFO - PROCESS | 2879 | 10:15:41 INFO - PROCESS | 2879 | [#5] yui.html Cycles:180 Average:14816.91 Median:25288.47 stddev:16839.05 (66.6%) stddev-sans-first:16853.63 10:15:41 INFO - PROCESS | 2879 | Values: 852.6 861.5 923.8 944.1 949.3 14523.4 14607.7 14666.9 14726.7 14737.1 13492.9 13502.9 13552.5 13553.7 13565.7 863.4 870.1 898.8 899.9 902.6 901.7 910.3 914.7 916.4 919.9 949.5 954.5 955.8 958.0 958.9 936.7 941.5 942.4 951.4 952.3 698.5 700.2 700.7 703.1 703.3 14555.9 14556.3 14574.6 14605.1 14857.0 13440.5 13452.0 13463.7 13805.9 14173.7 11198.8 11383.0 11553.4 11620.8 11657.2 3988.5 3998.6 4000.8 4075.2 4076.2 50745.5 52942.7 53912.0 53992.7 54172.2 10935.8 10990.7 11124.2 11157.1 11276.3 19453.0 19567.7 19638.4 19639.2 19664.8 8008.4 8311.3 8350.6 8373.0 8638.7 4516.3 4646.9 4718.4 4814.4 4867.1 3209.0 3230.3 3235.5 3239.6 3245.0 575.0 616.4 616.5 619.0 620.8 645.9 662.8 726.7 730.5 743.5 9034.9 9812.4 9869.8 9923.9 10015.6 588.9 667.5 702.9 725.0 726.9 4331.9 4624.6 4702.7 4728.5 4783.2 55226.4 55954.0 62909.7 63019.0 63323.4 52754.0 53090.2 53143.5 53196.5 54345.7 49919.8 50359.5 50373.0 50556.0 50635.7 9457.3 9569.0 9722.2 9913.9 9950.2 35162.6 35725.7 36574.0 36585.0 36606.0 45393.8 45896.3 45952.3 46567.0 47157.5 10405.8 11669.8 12349.3 12445.4 12831.6 13498.5 13584.2 13668.6 14000.3 14052.2 12014.6 12071.6 12137.7 12641.1 12715.7 11829.3 12069.9 12353.1 12712.6 12828.9 11399.3 11597.8 11888.2 12499.5 12771.4 10044.2 10205.2 10366.1 10664.4 10920.1 10416.5 10974.6 11105.6 11147.3 11209.2 10:15:41 INFO - PROCESS | 2879 | -------- Summary: end -------- 10:15:41 INFO - PROCESS | 2879 | 10:15:42 INFO - TEST-INFO | 2879: exit 0 10:15:42 INFO - TEST-OK | dromaeo_css | took 1108906ms 10:15:42 INFO - TEST-START | kraken 10:15:42 INFO - operating with platform_type : linux_ 10:15:42 INFO - Initialising browser for kraken test... 10:15:42 INFO - TEST-INFO | started process 3128 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQnBtV4/profile http://localhost:50667/getInfo.html) 10:15:45 INFO - PROCESS | 3128 | 10:15:45 INFO - PROCESS | 3128 | (process:3207): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 10:15:46 INFO - PROCESS | 3128 | __metrics Screen width/height:1600/1200 10:15:46 INFO - PROCESS | 3128 | colorDepth:24 10:15:46 INFO - PROCESS | 3128 | Browser inner width/height: 1024/697 10:15:46 INFO - PROCESS | 3128 | __metrics 10:15:47 INFO - TEST-INFO | 3128: exit 0 10:15:47 INFO - Browser initialized. 10:15:47 INFO - Running cycle 1/1 for kraken test... 10:15:47 INFO - TEST-INFO | started process 3256 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpQnBtV4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:48 INFO - PROCESS | 3256 | 10:15:48 INFO - PROCESS | 3256 | (process:3317): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 10:15:59 INFO - PROCESS | 3256 | RSS: Main: 141668352 10:15:59 INFO - PROCESS | 3256 | 10:16:01 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50667/tests/kraken/driver.html?testName=audio-beat-detection) 10:16:02 INFO - PROCESS | 3256 | RSS: Main: 147021824 10:16:02 INFO - PROCESS | 3256 | 10:16:04 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50667/tests/kraken/driver.html?testName=audio-dft) 10:16:05 INFO - PROCESS | 3256 | RSS: Main: 146157568 10:16:05 INFO - PROCESS | 3256 | 10:16:08 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50667/tests/kraken/driver.html?testName=audio-fft) 10:16:08 INFO - PROCESS | 3256 | RSS: Main: 146132992 10:16:08 INFO - PROCESS | 3256 | 10:16:10 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50667/tests/kraken/driver.html?testName=audio-oscillator) 10:16:11 INFO - PROCESS | 3256 | RSS: Main: 145842176 10:16:11 INFO - PROCESS | 3256 | 10:16:13 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50667/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:16:14 INFO - PROCESS | 3256 | RSS: Main: 145739776 10:16:14 INFO - PROCESS | 3256 | 10:16:17 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50667/tests/kraken/driver.html?testName=imaging-darkroom) 10:16:17 INFO - PROCESS | 3256 | RSS: Main: 146636800 10:16:17 INFO - PROCESS | 3256 | 10:16:21 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50667/tests/kraken/driver.html?testName=imaging-desaturate) 10:16:21 INFO - PROCESS | 3256 | RSS: Main: 146075648 10:16:21 INFO - PROCESS | 3256 | 10:16:24 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50667/tests/kraken/driver.html?testName=json-parse-financial) 10:16:25 INFO - PROCESS | 3256 | RSS: Main: 146186240 10:16:25 INFO - PROCESS | 3256 | 10:16:26 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50667/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:16:27 INFO - PROCESS | 3256 | RSS: Main: 146079744 10:16:27 INFO - PROCESS | 3256 | 10:16:29 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50667/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:16:29 INFO - PROCESS | 3256 | RSS: Main: 145625088 10:16:29 INFO - PROCESS | 3256 | 10:16:31 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50667/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:16:32 INFO - PROCESS | 3256 | RSS: Main: 146739200 10:16:32 INFO - PROCESS | 3256 | 10:16:34 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:16:35 INFO - PROCESS | 3256 | RSS: Main: 146485248 10:16:35 INFO - PROCESS | 3256 | 10:16:37 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:16:38 INFO - PROCESS | 3256 | RSS: Main: 146620416 10:16:38 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | Cycle 1(1): loaded http://localhost:50667/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50667/tests/kraken/driver.html?testName=ai-astar) 10:16:40 INFO - PROCESS | 3256 | RSS: Main: 148377600 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | __start_tp_report 10:16:40 INFO - PROCESS | 3256 | _x_x_mozilla_page_load 10:16:40 INFO - PROCESS | 3256 | _x_x_mozilla_page_load_details 10:16:40 INFO - PROCESS | 3256 | |i|pagename|runs| 10:16:40 INFO - PROCESS | 3256 | |0;ai-astar;111;106;112;111;121;122;112;110;112;110 10:16:40 INFO - PROCESS | 3256 | |1;audio-beat-detection;155;126;151;172;128;153;145;130;156;135 10:16:40 INFO - PROCESS | 3256 | |2;audio-dft;162;161;161;161;163;163;163;161;165;162 10:16:40 INFO - PROCESS | 3256 | |3;audio-fft;73;76;74;148;75;76;73;86;151;78 10:16:40 INFO - PROCESS | 3256 | |4;audio-oscillator;96;95;134;95;96;96;147;96;95;136 10:16:40 INFO - PROCESS | 3256 | |5;imaging-gaussian-blur;110;110;109;110;112;110;110;110;110;110 10:16:40 INFO - PROCESS | 3256 | |6;imaging-darkroom;150;147;148;146;147;148;150;148;147;146 10:16:40 INFO - PROCESS | 3256 | |7;imaging-desaturate;97;96;96;97;96;96;96;96;97;97 10:16:40 INFO - PROCESS | 3256 | |8;json-parse-financial;54;59;60;57;56;57;57;61;53;53 10:16:40 INFO - PROCESS | 3256 | |9;json-stringify-tinderbox;64;66;65;65;64;66;65;65;65;65 10:16:40 INFO - PROCESS | 3256 | |10;stanford-crypto-aes;83;83;81;89;82;88;96;81;88;82 10:16:40 INFO - PROCESS | 3256 | |11;stanford-crypto-ccm;114;118;116;118;111;112;113;113;117;113 10:16:40 INFO - PROCESS | 3256 | |12;stanford-crypto-pbkdf2;136;136;138;136;134;140;140;131;134;139 10:16:40 INFO - PROCESS | 3256 | |13;stanford-crypto-sha256-iterative;67;66;72;65;67;73;67;64;64;64 10:16:40 INFO - PROCESS | 3256 | __end_tp_report 10:16:40 INFO - PROCESS | 3256 | __start_cc_report 10:16:40 INFO - PROCESS | 3256 | _x_x_mozilla_cycle_collect,738 10:16:40 INFO - PROCESS | 3256 | __end_cc_report 10:16:40 INFO - PROCESS | 3256 | __startTimestamp1457115400291__endTimestamp 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | ------- Summary: start ------- 10:16:40 INFO - PROCESS | 3256 | Number of tests: 14 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#0] ai-astar Cycles:10 Average:112.70 Median:112.00 stddev:4.97 (4.4%) stddev-sans-first:5.23 10:16:40 INFO - PROCESS | 3256 | Values: 111.0 106.0 112.0 111.0 121.0 122.0 112.0 110.0 112.0 110.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:152.00 stddev:15.00 (9.9%) stddev-sans-first:15.48 10:16:40 INFO - PROCESS | 3256 | Values: 155.0 126.0 151.0 172.0 128.0 153.0 145.0 130.0 156.0 135.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.50 stddev:1.32 (0.8%) stddev-sans-first:1.39 10:16:40 INFO - PROCESS | 3256 | Values: 162.0 161.0 161.0 161.0 163.0 163.0 163.0 161.0 165.0 162.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#3] audio-fft Cycles:10 Average:91.00 Median:75.50 stddev:31.07 (41.1%) stddev-sans-first:32.26 10:16:40 INFO - PROCESS | 3256 | Values: 73.0 76.0 74.0 148.0 75.0 76.0 73.0 86.0 151.0 78.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#4] audio-oscillator Cycles:10 Average:108.60 Median:95.50 stddev:21.24 (22.2%) stddev-sans-first:22.03 10:16:40 INFO - PROCESS | 3256 | Values: 96.0 95.0 134.0 95.0 96.0 96.0 147.0 96.0 95.0 136.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.74 (0.7%) stddev-sans-first:0.78 10:16:40 INFO - PROCESS | 3256 | Values: 110.0 110.0 109.0 110.0 112.0 110.0 110.0 110.0 110.0 110.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:1.42 (1.0%) stddev-sans-first:1.24 10:16:40 INFO - PROCESS | 3256 | Values: 150.0 147.0 148.0 146.0 147.0 148.0 150.0 148.0 147.0 146.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 10:16:40 INFO - PROCESS | 3256 | Values: 97.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 97.0 97.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:2.79 (4.9%) stddev-sans-first:2.78 10:16:40 INFO - PROCESS | 3256 | Values: 54.0 59.0 60.0 57.0 56.0 57.0 57.0 61.0 53.0 53.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.60 10:16:40 INFO - PROCESS | 3256 | Values: 64.0 66.0 65.0 65.0 64.0 66.0 65.0 65.0 65.0 65.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.50 stddev:4.85 (5.7%) stddev-sans-first:5.08 10:16:40 INFO - PROCESS | 3256 | Values: 83.0 83.0 81.0 89.0 82.0 88.0 96.0 81.0 88.0 82.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#11] stanford-crypto-ccm Cycles:10 Average:114.50 Median:115.00 stddev:2.55 (2.2%) stddev-sans-first:2.70 10:16:40 INFO - PROCESS | 3256 | Values: 114.0 118.0 116.0 118.0 111.0 112.0 113.0 113.0 117.0 113.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.40 Median:137.00 stddev:2.91 (2.1%) stddev-sans-first:3.09 10:16:40 INFO - PROCESS | 3256 | Values: 136.0 136.0 138.0 136.0 134.0 140.0 140.0 131.0 134.0 139.0 10:16:40 INFO - PROCESS | 3256 | 10:16:40 INFO - PROCESS | 3256 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:3.21 (4.8%) stddev-sans-first:3.41 10:16:40 INFO - PROCESS | 3256 | Values: 67.0 66.0 72.0 65.0 67.0 73.0 67.0 64.0 64.0 64.0 10:16:40 INFO - PROCESS | 3256 | -------- Summary: end -------- 10:16:40 INFO - PROCESS | 3256 | 10:16:41 INFO - TEST-INFO | 3256: exit 0 10:16:41 INFO - TEST-OK | kraken | took 58663ms 10:16:41 INFO - TEST-START | v8_7 10:16:41 INFO - operating with platform_type : linux_ 10:16:41 INFO - Initialising browser for v8_7 test... 10:16:41 INFO - TEST-INFO | started process 3545 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpz4c4wd/profile http://localhost:50667/getInfo.html) 10:16:44 INFO - PROCESS | 3545 | 10:16:44 INFO - PROCESS | 3545 | (process:3624): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 10:16:45 INFO - PROCESS | 3545 | __metrics Screen width/height:1600/1200 10:16:45 INFO - PROCESS | 3545 | colorDepth:24 10:16:45 INFO - PROCESS | 3545 | Browser inner width/height: 1024/697 10:16:45 INFO - PROCESS | 3545 | __metrics 10:16:46 INFO - TEST-INFO | 3545: exit 0 10:16:46 INFO - Browser initialized. 10:16:46 INFO - Running cycle 1/1 for v8_7 test... 10:16:46 INFO - TEST-INFO | started process 3673 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpz4c4wd/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:16:47 INFO - PROCESS | 3673 | 10:16:47 INFO - PROCESS | 3673 | (process:3738): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 10:16:58 INFO - PROCESS | 3673 | RSS: Main: 141967360 10:16:58 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | Cycle 1(1): loaded http://localhost:50667/tests/v8_7/run.html (next: http://localhost:50667/tests/v8_7/run.html) 10:17:20 INFO - PROCESS | 3673 | RSS: Main: 145203200 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | __start_tp_report 10:17:20 INFO - PROCESS | 3673 | _x_x_mozilla_page_load 10:17:20 INFO - PROCESS | 3673 | _x_x_mozilla_page_load_details 10:17:20 INFO - PROCESS | 3673 | |i|pagename|runs| 10:17:20 INFO - PROCESS | 3673 | |0;Richards;166.77785190126752 10:17:20 INFO - PROCESS | 3673 | |1;DeltaBlue;173.97355601948504 10:17:20 INFO - PROCESS | 3673 | |2;Encrypt;279.09572983533354 10:17:20 INFO - PROCESS | 3673 | |3;Decrypt;5175.257731958763 10:17:20 INFO - PROCESS | 3673 | |4;RayTrace;937.207122774133 10:17:20 INFO - PROCESS | 3673 | |5;Earley;561.7977528089888 10:17:20 INFO - PROCESS | 3673 | |6;Boyer;11133.333333333334 10:17:20 INFO - PROCESS | 3673 | |7;RegExp;39862.74509803922 10:17:20 INFO - PROCESS | 3673 | |8;Splay;525.2100840336135 10:17:20 INFO - PROCESS | 3673 | |9;NavierStokes;4826.923076923077 10:17:20 INFO - PROCESS | 3673 | __end_tp_report 10:17:20 INFO - PROCESS | 3673 | __startTimestamp1457115440704__endTimestamp 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | ------- Summary: start ------- 10:17:20 INFO - PROCESS | 3673 | Number of tests: 10 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#0] Richards Cycles:1 Average:166.78 Median:166.78 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 166.8 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#1] DeltaBlue Cycles:1 Average:173.97 Median:173.97 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 174.0 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#2] Encrypt Cycles:1 Average:279.10 Median:279.10 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 279.1 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 5175.3 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#4] RayTrace Cycles:1 Average:937.21 Median:937.21 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 937.2 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#5] Earley Cycles:1 Average:561.80 Median:561.80 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 561.8 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#6] Boyer Cycles:1 Average:11133.33 Median:11133.33 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 11133.3 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#7] RegExp Cycles:1 Average:39862.75 Median:39862.75 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 39862.7 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#8] Splay Cycles:1 Average:525.21 Median:525.21 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 525.2 10:17:20 INFO - PROCESS | 3673 | 10:17:20 INFO - PROCESS | 3673 | [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 10:17:20 INFO - PROCESS | 3673 | Values: 4826.9 10:17:20 INFO - PROCESS | 3673 | -------- Summary: end -------- 10:17:20 INFO - PROCESS | 3673 | 10:17:21 INFO - TEST-INFO | 3673: exit 0 10:17:21 INFO - TEST-OK | v8_7 | took 40370ms 10:17:21 INFO - SUITE-END | took 1207s 10:17:21 INFO - Completed test suite (00:20:08) 10:17:21 INFO - Working with test: dromaeo_css 10:17:21 INFO - Generating results file: dromaeo_css 10:17:21 INFO - Working with test: kraken 10:17:21 INFO - Generating results file: kraken 10:17:21 INFO - javascript benchmark 10:17:21 INFO - Working with test: v8_7 10:17:21 INFO - Generating results file: v8_7 10:17:21 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:17:21 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,52,43]] 10:17:21 INFO - process_Request line: dromaeo_css 7365.37 graph.html#tests=[[72,52,43]] 10:17:21 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:17:22 INFO - process_Request line: kraken 1498.60 graph.html#tests=[[232,52,43]] 10:17:22 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:17:22 INFO - process_Request line: v8_7 21905.46 graph.html#tests=[[230,52,43]] 10:17:22 INFO - RETURN: dromaeo_css: 7365.37 10:17:22 INFO - RETURN: kraken: 1498.60 10:17:22 INFO - RETURN: v8_7: 21905.46 10:17:22 INFO - javascript benchmark 10:17:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3293.637699853014, 3301.141703157179, 3308.768386212966, 3338.666133386661, 3339.3154403347316, 5364.5708343332535, 5371.382291036527, 5380.085385484467, 5394.1908713692965, 5407.405185429609, 5383.138697808351, 5401.378841433237, 5402.729863506819, 5407.242985981956, 5429.429909859467, 4909.680870743408, 4954.504549545035, 4987.8254261100865, 5000.97499512502, 5069.290299358093, 4831.420229572456, 4956.603471722254, 4986.0, 4991.550760431561, 5118.232265160237, 4971.701697898119, 5011.223260394627, 5025.447200807908, 5029.094762942669, 5033.194688849785, 4914.533119353656, 5004.449510553836, 5010.298558201855, 5013.473585273548, 5019.096562618727, 4872.4640289568115, 4879.438864530589, 4942.752862356903, 4955.4796746341835, 4956.1822299320565, 5171.172612382038, 5198.350206224222, 5208.8437346879655, 5218.765155568009, 5229.555487783543, 5737.655740655574, 5746.195532625436, 5756.453136951983, 5768.105943578752, 5769.394213607595, 5737.0, 5750.079987202028, 5772.826815195551, 5822.592418530705, 5831.970840145815, 5412.702301373423, 5452.536534394581, 5462.9726851365485, 5463.153211252223, 5481.205225242357, 5889.352171261158, 5901.911471327933, 5905.970470147622, 5909.6158749681135, 5921.496672782776, 5568.16477528374, 5589.888202235932, 5643.5767317451355, 5649.265595472644, 5748.051571490741, 5746.965546201724, 5747.942520574741, 5756.194132821408, 5757.309122905278, 5842.474177324061, 5721.599488035881, 5726.141078838207, 5726.627769194989, 5730.08318669011, 5823.475887170175, 5685.374608793029, 5701.2018317435595, 5770.105633626795, 5830.591858569859, 5849.736761845727, 4418.624416924597, 4419.712718673276, 4428.092241090569, 4436.733795972252, 4448.621867141264, 3978.8607398740905, 3984.7808370539897, 4003.4195041718785, 4007.6393124618926, 4021.2158629067635, 5390.380106287748, 5413.756380962866, 5484.122540393518, 5509.035918714289, 5513.669179849222, 5844.1233814927255, 5872.559558033165, 5881.7647294107755, 5883.705814709333, 5884.264466941632, 5481.013417584793, 5513.393526712059, 5535.391106978229, 5543.805966791143, 5555.083411237181, 4953.975230123826, 4984.675996060245, 5034.043531728959, 5038.294638750578, 5051.0, 5849.853753656193, 5864.589478736533, 5872.0, 5895.263092113486, 5896.1450589664255, 5779.653220806765, 5824.242848429677, 5828.358880523139, 5834.591578589628, 5843.970780146242, 5708.115242137475, 5781.364049954585, 5782.653040817564, 5794.449527294901, 5905.202797622266, 5466.972665136649, 5493.038718224215, 5501.0098182327565, 5502.697351645698, 5522.420145884625, 5786.42135786418, 5832.0, 5866.061430171152, 5868.5305175585, 5869.559783016205, 5585.497305242548, 5655.123455864347, 5710.229119068873, 5710.543156547383, 5734.512566431733, 5613.971930140323, 5654.264945557051, 5682.14767784819, 5684.7441865117, 5689.715514224189, 5588.105903055654, 5589.385167631639, 5592.328920529562, 5594.356648985256, 5600.215969764317, 5583.525400341016, 5610.971945140248, 5703.372629010722, 5718.228039214653, 5821.738021788945, 5511.779528818803, 5531.502164805026, 5559.138333558305, 5576.470235327637, 5581.386047534849], "unit": "score", "name": "dojo.html", "value": 5338.4731336991945}, {"lowerIsBetter": false, "replicates": [555.8026900450342, 569.0014025385448, 574.207593520941, 598.7066337494628, 661.7650733989434, 40329.0, 40890.7955460223, 41308.793456032756, 41397.9650508737, 41463.0, 45557.0, 46397.0, 46501.30248046282, 46837.06325873481, 46884.296735549, 8833.46999180048, 9146.451212927212, 9169.54152292386, 9170.954145229265, 9180.357374983754, 8823.823523529525, 8968.64125434985, 8993.055729148444, 9010.459372437643, 9046.095390460967, 8966.775830604221, 9059.411138275991, 9086.04596517366, 9108.271338292954, 9140.0, 8940.15068568485, 8970.641174353019, 8973.371863969525, 9007.594658240396, 9019.188273055424, 8913.331500137516, 8959.148880856308, 8965.910340896638, 9003.549822508914, 9008.324375671851, 42382.78808605968, 42500.1499970002, 42808.78595607033, 42825.14349713019, 42856.357154642705, 3756.1736417988272, 3803.315403227418, 3841.135744457503, 3904.707146963989, 4049.3925911113274, 28827.42345153106, 28948.0, 29168.124956251348, 33002.504962425584, 33448.6655133448, 4320.589543993316, 4333.263345231287, 4336.8698939031565, 4365.9126817463475, 4371.628411585019, 295815.0, 302371.0, 303500.0, 304511.0, 304515.4774226137, 45629.54370456253, 46134.538654613694, 46231.0, 46458.535414646096, 67476.0, 44714.55285447169, 44759.77620111879, 45199.77400113044, 45259.54740452553, 45300.0, 16392.836071639133, 16519.174041297887, 16574.502764917073, 16575.6684866302, 16616.169191540375, 13245.072844900957, 13314.933425333005, 13455.0, 13468.730625387438, 13669.384877680528, 15442.382304707686, 15468.0, 15635.452759153375, 15650.921745391428, 15688.843111568738, 6063.272407311151, 6160.168377269101, 6188.4739797117745, 6219.906701399483, 6282.0, 1076.1767248055244, 1102.9834552481718, 1145.6792098212513, 1164.6564263542275, 1241.6652098993618, 30836.0, 31017.534736978963, 31064.68935310618, 31369.215769605944, 31789.0, 1159.1248607301602, 1198.0176255470606, 1215.5502464088345, 1226.1723336747839, 1235.178606226849, 16059.839401605834, 17320.30718771235, 17785.555361116076, 17793.911030445022, 18064.18711158001, 532724.0, 533185.0, 534986.0, 537380.0, 823354.0, 56780.148297775566, 57410.0, 57433.71283143557, 57535.42464575385, 57578.0, 1493.148905124069, 1513.9772903406458, 1523.5962469945473, 1526.282647155835, 1527.4577524978695, 30237.697623023487, 31233.0, 31601.0, 31916.840415798702, 34032.65967340394, 51757.482425175265, 51895.2215716757, 52344.47655523396, 52431.475685244186, 52475.47524524706, 335927.0, 336260.0, 338447.0, 338673.0, 341149.0, 35131.121721957155, 35297.64702352943, 35490.46764298486, 35668.643313566536, 47917.76041119772, 46709.0, 46995.53004469912, 47024.0, 47072.529274706816, 47870.0, 38992.83021509359, 39105.6089439102, 39365.0, 39670.80164599274, 39957.2008559833, 39522.20955580815, 40718.796406017784, 41264.587354127274, 42084.36873446962, 43851.34222986594, 38495.230095397375, 38744.0, 43702.34446483369, 43880.78059609682, 43902.34146487741, 33443.33113337671, 35269.726127313756, 35757.46363804597, 35824.46263306, 36005.63994360023, 27002.189934302, 27691.0, 27887.884484620387, 28150.85924570364, 28200.29499262535], "unit": "score", "name": "ext.html", "value": 19893.67567840684}, {"lowerIsBetter": false, "replicates": [392.07274795109566, 396.90474286171326, 398.37256321293967, 401.25767330438686, 402.9516458025037, 15086.3211155498, 15395.307211175495, 17039.488815335546, 17569.56076098098, 17664.64670706585, 16318.918405407956, 16893.408730694428, 17245.74131388027, 17525.0, 17721.113944302797, 5220.817271395502, 5430.91853622196, 5436.184572314165, 5436.21174929635, 5459.727013649302, 5341.839744807662, 5396.10964190908, 5580.944190558103, 5603.691796951166, 5609.1305847593485, 5297.576193904498, 5540.9168862467095, 5543.029969755296, 5584.581156413265, 5593.356763972155, 5373.220882971987, 5494.780208791663, 5503.724813759316, 5746.683932383718, 5791.65250084992, 5614.410486898899, 5668.829935101953, 5679.7160141993145, 5684.34630017545, 5687.061634830248, 14302.570922872226, 14348.282585870666, 19127.617447651108, 20238.898805506025, 21484.462888427915, 2068.4415207893935, 2178.7494438139656, 2227.3429338345227, 2298.1152256381247, 2362.362162216192, 10357.844632330522, 10703.83944240837, 10893.455327233607, 11543.711407214803, 13844.93077534616, 1810.4568629411256, 1874.2034635280088, 1930.3629802165392, 1939.2533874458295, 1979.901004949761, 58225.0, 59528.0, 60589.0, 61456.69271653658, 61669.0, 10661.413622250695, 10868.891311086787, 11136.38749868765, 11146.498407571678, 11254.15593830466, 14818.62953426173, 14850.554483365517, 14869.479568215276, 14900.776488352683, 14996.62508437276, 8259.463134896212, 8423.957880210683, 8598.140185981478, 8612.5693715314, 8644.524551149621, 8400.579971001425, 8448.11294814048, 8451.197136272045, 8462.830743385099, 8526.658933642584, 4628.67599268048, 4749.430068391815, 4825.662203645781, 4829.333879811459, 4883.755812209375, 1950.1224448998007, 1958.8432925365937, 1992.3026940570974, 1997.3408775104472, 2001.6388855578055, 996.785691076422, 1147.690123666622, 1168.7896178687747, 1183.1540448579308, 1185.9229150105216, 15645.295961681752, 16044.197790110682, 17102.74345884788, 17199.0, 17835.10824458772, 1035.0736091198335, 1041.95832166714, 1068.1988508618535, 1103.5971870267456, 1139.0830381542771, 9647.0, 9800.460974646463, 9870.16103631185, 9892.901070989343, 9963.900360996297, 449130.0, 450219.0, 455891.0, 696604.0, 712592.0, 76562.0, 78898.21101789024, 79750.60124699339, 80348.68843563218, 82352.58823705843, 1187.1155988788234, 1214.9028077753758, 1227.5396726227486, 1230.366361323919, 1230.5139469909336, 16175.757363639319, 16191.595210119842, 16212.675746484769, 16261.918690406472, 16489.175541222652, 33630.0, 34092.0, 34577.65422345845, 35835.64164358323, 35971.82014089913, 45428.77285613551, 46065.30902036405, 48073.75963120162, 49294.75352623214, 49554.0, 7428.368588670023, 7585.0, 7807.843843123219, 7817.648205830865, 8024.438289319692, 10840.566377344818, 11464.885351146382, 11503.252288601214, 11563.6530904073, 12144.149909506603, 10226.948865255627, 10250.282480226611, 10325.122364599094, 10436.956304369502, 10778.622748203776, 9630.759231019281, 9942.801143977225, 10020.949895250476, 10171.13545348624, 10851.969062939008, 10006.723609831528, 10463.215258855464, 10516.842247366143, 10904.182186335898, 11056.557737690724, 7947.642356093874, 8089.110197878346, 8090.393220508367, 8341.791455213668, 8350.373721970755, 10635.787284254426, 10774.353538787698, 10799.514021869192, 10893.727656808644, 11006.064484518905], "unit": "score", "name": "jquery.html", "value": 8769.605171133771}, {"lowerIsBetter": false, "replicates": [1535.224711520682, 1544.5289186798025, 1749.3177660712324, 1842.9907850460745, 1880.210311669098, 8307.50154990701, 8311.584420778967, 8493.617787199575, 8495.490270583769, 8508.574571271427, 8507.532085735282, 8525.91474085259, 8581.09898460662, 8607.311415086793, 8617.095205003463, 2494.2267896951957, 2518.3704073981503, 2524.0787112703915, 2529.152733834168, 2537.5051864886354, 2432.683751112353, 2488.8880000399936, 2489.7634724701124, 2498.850068995857, 2499.800015998725, 2150.3656421355736, 2203.7025001624734, 2411.1560953666253, 2478.293686299405, 2479.1323036937106, 2411.5538625354366, 2436.2082323245018, 2436.451798345367, 2450.0444826517673, 2452.6688896998853, 2392.198613464474, 2435.0625009371342, 2443.731189569146, 2444.8044156467618, 2451.3381387025584, 8374.581270936429, 8378.329733621296, 8381.83236335276, 8467.70363037297, 8480.66077356905, 5043.924341134886, 5227.398849132361, 5242.2136679498, 5243.632945693803, 5247.0, 3228.4027454920915, 3338.549295845054, 3376.9493457598155, 3388.0513456232047, 3417.094469965461, 1136.4658610453073, 1149.4425203776161, 1151.7984352738322, 1195.7010747313172, 1209.1596340543326, 11444.656660300205, 11567.942160289229, 11599.130065245055, 11605.071594272438, 11608.593699220572, 4542.136993971135, 4601.401817763669, 4623.375844261049, 4633.559811817872, 4641.8375356863025, 9527.618895244113, 9537.570809313598, 9543.093406126407, 9566.18687411564, 9586.712398628053, 5062.265971434122, 5081.0, 5084.77118529667, 5101.642884998088, 5114.565261952701, 4566.246569316092, 4589.655775816827, 4600.148972440109, 4657.184992626277, 4671.11248862715, 3762.4168253920684, 3779.8110094495164, 3786.1859700164596, 3843.0584506795453, 3864.9613503864603, 1071.239420011785, 1083.0360978728938, 1085.5006696919506, 1086.5545126498098, 1090.9345439273668, 1048.7535429174138, 1101.1906248907046, 1158.6813626252679, 1202.5610652111916, 1390.847006829248, 7826.686932522749, 7904.170062143392, 7907.327877130509, 7918.841623167505, 7931.524108553505, 830.9916900831035, 1295.5271325965955, 1297.4356155072574, 1306.1510018487868, 1320.544412177797, 5976.9402305977255, 5987.31145918225, 6026.758929642765, 6041.184440100618, 6049.274087109575, 9709.174720148867, 10279.94860025695, 10358.119559837347, 10370.585176593002, 10370.96290370957, 10185.33795303303, 10220.335678180894, 10261.74345641365, 10314.896851031544, 10412.739681508023, 7740.961295193488, 7875.448718589759, 7886.72396466121, 7912.920870791334, 7974.322182614543, 7999.160088190656, 8049.83900322002, 8113.269805717514, 8127.390445716713, 8257.38069644767, 9090.272778177598, 9222.95388523053, 9368.110029547182, 9409.48247846375, 9489.240860730986, 9459.10138536838, 9482.810343793224, 9592.95203523978, 9653.03469653029, 9819.263555233243, 7607.27730865589, 7642.159362470013, 7715.498492597963, 7726.18875018115, 7738.342240909586, 8081.959590202011, 8218.958905205436, 8229.05365882931, 8272.131426200162, 8274.13121622221, 7607.087149542091, 7853.528788272722, 7858.960705196666, 7925.28672419485, 7936.682532698627, 6997.965010174917, 7003.8249043772275, 7066.364027237573, 7449.851002979802, 7945.48354356965, 7633.0458692663415, 7681.654325555475, 7701.537907725555, 7706.229377062249, 7715.267049630276, 6952.096227490394, 7023.086998690342, 7041.401480874183, 7053.329933656294, 7080.256573059754, 6731.293214212633, 6744.359285867835, 6749.055132281386, 6771.3905748482875, 6897.241303456515], "unit": "score", "name": "mootools.html", "value": 4851.117268823653}, {"lowerIsBetter": false, "replicates": [345.39383382164306, 356.15768707007936, 360.32978659692964, 361.81366596202975, 365.1145971020277, 6422.100905873176, 6444.194475690539, 6460.7092680829355, 6484.773032943848, 6578.243501997272, 6441.7423303067935, 6466.902996455057, 6469.094326794241, 6479.86602344589, 6486.318936511669, 4919.065377578248, 4922.286268491067, 4922.753862306889, 4943.876403089925, 4953.826616068438, 4965.3048573199785, 4970.304157417964, 4972.154733695263, 4978.2532620107095, 4979.7261150636705, 4818.373611430509, 4925.876853078666, 4928.33467481889, 4931.161702510564, 4945.554900058994, 4839.588634966032, 4858.29554714568, 4876.414830220361, 4876.658633895611, 4880.121578115938, 4274.102438487921, 4288.978555107205, 4291.742495450252, 4302.41917341158, 4308.870733877989, 5987.311459182162, 6109.0, 6113.908291375633, 6129.785457508966, 6133.846653833645, 5775.451332123442, 5782.855428614276, 5784.797532086357, 5785.190073389729, 5789.8263052108505, 6834.692438840264, 6865.5537390069485, 6870.20992585854, 6877.862442751167, 6885.0, 4761.547652972962, 4767.022760334158, 4773.928391074137, 4774.379330686988, 4787.8563643090765, 6920.688569014406, 6994.545354551989, 7014.298570142996, 7083.327083927019, 7085.787426377217, 4846.5638092571835, 4860.513948605111, 4861.927071093936, 4866.732329721899, 4874.268859671007, 4910.852674419773, 4928.605711543068, 4929.926051109236, 4936.111499929975, 4940.135476291706, 5498.477644623755, 5693.800716974886, 5697.829065127969, 5709.857253568611, 5711.857203569944, 6814.420774234246, 6821.0, 6833.214180369222, 6856.725730970706, 6895.862082758417, 1705.249690136336, 1728.0150314320717, 1738.7304967730022, 1750.3348727483474, 1751.5621094726318, 1166.125405945541, 1174.4304012553905, 1180.9468573914194, 1189.8096304591397, 1190.9642710718692, 6481.09264702942, 6501.967490162613, 6506.154199953976, 6515.967420162869, 6539.574927629689, 6917.0, 6975.441964642816, 6982.546134501241, 6990.895136572955, 6991.7902462926195, 6399.392057754506, 6479.6760161991715, 6490.675466226669, 6492.156019717501, 6498.870022599522, 3784.7729136251914, 3794.449804778347, 3807.0, 3808.6953043756434, 3810.314143454205, 7073.681684324218, 7083.150021997393, 7106.2183159852375, 7106.92893071073, 7116.679749411289, 6926.272741362084, 7093.290670932865, 7129.108861392326, 7171.462140339496, 7193.172785129673, 7091.716331346688, 7093.964530177316, 7105.0, 7116.928830711627, 7121.394681452135, 6934.618595977269, 6987.5108742389575, 6987.7204911802955, 6989.33601307875, 6990.322322642631, 6637.469002479694, 6882.380585747307, 6884.070650462324, 6891.827704307232, 6915.792526224222, 7051.330123638246, 7063.682134304071, 7116.50184487081, 7117.75087871922, 7118.9644051779405, 4894.535019173173, 4894.681845680019, 4895.877603059809, 4901.90196196067, 4906.950930490792, 6828.9658551706925, 6976.19773726028, 6978.3370579796865, 6980.092587963532, 6990.755323563651, 6755.729770809113, 6774.288699686462, 6782.423494002867, 6793.558418702769, 6795.354441328263, 6694.464442844658, 6733.393994540319, 6765.763198288037, 6769.796906092825, 6773.424258938046, 6471.191101112361, 6597.505187111082, 6632.204135503771, 6640.667966601747, 6643.936970084763, 6748.223954245324, 6750.763723269662, 6759.425448836904, 6769.458443324621, 6770.898436523555, 6535.934640653532, 6577.835554111186, 6618.338166183343, 6636.336366363518, 6658.267590564937], "unit": "score", "name": "prototype.html", "value": 5128.421334202842}, {"lowerIsBetter": false, "replicates": [852.560931120473, 861.4917198852676, 923.7505873414178, 944.0889541592363, 949.2785483032895, 14523.41906323747, 14607.707845843077, 14666.926665366685, 14726.705465890676, 14737.115773053625, 13492.932535337333, 13502.864971350284, 13552.457901683945, 13553.728925421485, 13565.728685426286, 863.3567991846083, 870.0864092702637, 898.7663207566047, 899.9100089991014, 902.6073657958809, 901.7204666553389, 910.308165793998, 914.6981496106303, 916.4043371808311, 919.8988111307751, 949.463553092504, 954.4607296877242, 955.8183945050453, 958.018031517693, 958.8753462049924, 936.6581197862765, 941.4680705401427, 942.4251206763869, 951.3911096898025, 952.2572393533055, 698.5110422704076, 700.1702981966336, 700.663681432915, 703.0965209705504, 703.2721133626723, 14555.854441455556, 14556.344964476624, 14574.615411535888, 14605.12369257848, 14857.0, 13440.5295814647, 13452.0, 13463.730725385438, 13805.861941380557, 14173.71652566943, 11198.832017519662, 11383.03244224242, 11553.422328883522, 11620.76758464826, 11657.242279251906, 3988.5213774346976, 3998.6401223889698, 4000.819963101638, 4075.246079475277, 4076.2458945095254, 50745.49254507445, 52942.73528632371, 53912.0, 53992.73003634957, 54172.187417188776, 10935.83596246057, 10990.670279891616, 11124.165687573464, 11157.051650609788, 11276.32342059479, 19453.0, 19567.706484402745, 19638.410847674593, 19639.214431422584, 19664.803351966297, 8008.399370047153, 8311.33509319253, 8350.582470876432, 8372.99524057117, 8638.697645582375, 4516.322551617218, 4646.883827904329, 4718.4337879454, 4814.350062741555, 4867.123917694778, 3209.0, 3230.3377807549396, 3235.498497732855, 3239.611246650417, 3245.026492052375, 574.9587130711371, 616.3589866538839, 616.4759954039031, 619.0250355689807, 620.7889317632026, 645.8740545593564, 662.7647185249264, 726.691156258588, 730.5251586468731, 743.5241445474901, 9034.86447703285, 9812.411255324703, 9869.753256168653, 9923.851142232872, 10015.649452269136, 588.8851673923543, 667.5093806052547, 702.8980797784293, 725.0284618610981, 726.8655298769639, 4331.935020974688, 4624.630029597624, 4702.741349225757, 4728.503507131771, 4783.23468245086, 55226.44773552213, 55954.0, 62909.68545157245, 63019.0, 63323.36676633267, 52754.0, 53090.20364694455, 53143.46856531385, 53196.46803531992, 54345.72827135918, 49919.75040124776, 50359.49640503621, 50372.99254014899, 50556.0, 50635.746821266395, 9457.290703197425, 9568.95215523918, 9722.173615242784, 9913.900860991298, 9950.154236889946, 35162.648373515935, 35725.74959876392, 36574.0, 36585.0, 36606.0, 45393.77303113463, 45896.31155532603, 45952.31071533863, 46567.0, 47157.52842471531, 10405.791884162425, 11669.76660466769, 12349.259044457362, 12445.439955201857, 12831.550895718605, 13498.460061597427, 13584.184948903097, 13668.589942301747, 14000.299985000505, 14052.156870587798, 12014.63956081319, 12071.637850864488, 12137.69655758613, 12641.115121941375, 12715.682107947005, 11829.349385783864, 12069.939650302043, 12353.135280530276, 12712.555060572835, 12828.935855320664, 11399.259048161843, 11597.826032609686, 11888.167828252286, 12499.500019999461, 12771.425285861973, 10044.196464282986, 10205.183585313305, 10366.067053964878, 10664.360138391721, 10920.126389888948, 10416.531256093342, 10974.561017559208, 11105.555777768799, 11147.275427097213, 11209.215354925404], "unit": "score", "name": "yui.html", "value": 6889.614651125495}], "name": "dromaeo_css", "value": 7365.373624391379}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 106.0, 112.0, 111.0, 121.0, 122.0, 112.0, 110.0, 112.0, 110.0], "unit": "score", "name": "ai-astar", "value": 112.7}, {"lowerIsBetter": true, "replicates": [155.0, 126.0, 151.0, 172.0, 128.0, 153.0, 145.0, 130.0, 156.0, 135.0], "unit": "score", "name": "audio-beat-detection", "value": 145.1}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 161.0, 161.0, 163.0, 163.0, 163.0, 161.0, 165.0, 162.0], "unit": "score", "name": "audio-dft", "value": 162.2}, {"lowerIsBetter": true, "replicates": [73.0, 76.0, 74.0, 148.0, 75.0, 76.0, 73.0, 86.0, 151.0, 78.0], "unit": "score", "name": "audio-fft", "value": 91.0}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 134.0, 95.0, 96.0, 96.0, 147.0, 96.0, 95.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 108.6}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 109.0, 110.0, 112.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [150.0, 147.0, 148.0, 146.0, 147.0, 148.0, 150.0, 148.0, 147.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 147.7}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 97.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [54.0, 59.0, 60.0, 57.0, 56.0, 57.0, 57.0, 61.0, 53.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 56.7}, {"lowerIsBetter": true, "replicates": [64.0, 66.0, 65.0, 65.0, 64.0, 66.0, 65.0, 65.0, 65.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 65.0}, {"lowerIsBetter": true, "replicates": [83.0, 83.0, 81.0, 89.0, 82.0, 88.0, 96.0, 81.0, 88.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [114.0, 118.0, 116.0, 118.0, 111.0, 112.0, 113.0, 113.0, 117.0, 113.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 114.5}, {"lowerIsBetter": true, "replicates": [136.0, 136.0, 138.0, 136.0, 134.0, 140.0, 140.0, 131.0, 134.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 136.4}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 72.0, 65.0, 67.0, 73.0, 67.0, 64.0, 64.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.9}], "name": "kraken", "value": 1498.6000000000001}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.77785190126752], "unit": "score", "name": "Richards", "value": 211.67079199999998}, {"lowerIsBetter": false, "replicates": [173.97355601948504], "unit": "score", "name": "DeltaBlue", "value": 380.04626400000006}, {"lowerIsBetter": false, "replicates": [279.09572983533354], "unit": "score", "name": "Encrypt", "value": 953.7265229999995}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [937.207122774133], "unit": "score", "name": "RayTrace", "value": 789.568263}, {"lowerIsBetter": false, "replicates": [561.7977528089888], "unit": "score", "name": "Earley", "value": 1186.3041400000002}, {"lowerIsBetter": false, "replicates": [11133.333333333334], "unit": "score", "name": "Boyer", "value": 59.86194610778439}, {"lowerIsBetter": false, "replicates": [39862.74509803922], "unit": "score", "name": "RegExp", "value": 22.85304230201673}, {"lowerIsBetter": false, "replicates": [525.2100840336135], "unit": "score", "name": "Splay", "value": 155.15886400000005}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 21905.456637431653}]} 10:17:22 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,43]]", "result": "21905.46"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,43]]", "result": "1498.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,43]]", "result": "7365.37"}}} 10:17:22 INFO - Return code: 0 10:17:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:17:22 INFO - # TBPL SUCCESS # 10:17:22 INFO - Running post-action listener: _resource_record_post_action 10:17:22 INFO - Running post-run listener: _resource_record_post_run 10:17:23 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 282624; Write bytes: 506494976; Read time: 40; Write time: 594968 10:17:23 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 248684544; Read time: 0; Write time: 392852 10:17:23 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 282624; Write bytes: 257810432; Read time: 40; Write time: 202116 10:17:23 INFO - Running post-run listener: _upload_blobber_files 10:17:23 INFO - Blob upload gear active. 10:17:23 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 10:17:23 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 10:17:23 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'] 10:17:23 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 10:17:23 INFO - (blobuploader) - INFO - Open directory for files ... 10:17:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:17:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:17:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:17:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:17:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:17:24 INFO - (blobuploader) - INFO - Done attempting. 10:17:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:17:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:17:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:17:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:17:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:17:25 INFO - (blobuploader) - INFO - Done attempting. 10:17:25 INFO - (blobuploader) - INFO - Iteration through files over. 10:17:25 INFO - Return code: 0 10:17:25 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 10:17:25 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 10:17:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68070cf7c7207a871f4b1d11996aa2d49513b69a5f44d714545dfa50e6b1b2e20239c4e60870f92efae67055a59b728dbe683a1f930421d3a8f1ba205c8a8419", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/adb0b4cd98ce538c980cb21c275acb51c99d1b6775d45661ebd9ad4c4c572d1bda930ba525910c2a6c8c9aaaf73dcc8ece65bedd9a05fa97fa16b7d53da7201b"} 10:17:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 10:17:25 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 10:17:25 INFO - Contents: 10:17:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/68070cf7c7207a871f4b1d11996aa2d49513b69a5f44d714545dfa50e6b1b2e20239c4e60870f92efae67055a59b728dbe683a1f930421d3a8f1ba205c8a8419", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/adb0b4cd98ce538c980cb21c275acb51c99d1b6775d45661ebd9ad4c4c572d1bda930ba525910c2a6c8c9aaaf73dcc8ece65bedd9a05fa97fa16b7d53da7201b"} 10:17:25 INFO - Running post-run listener: copy_logs_to_upload_dir 10:17:25 INFO - Copying logs to upload dir... 10:17:25 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 10:17:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1331.940102 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 11 secs) (at 2016-03-04 10:17:25.501945) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-04 10:17:25.505010) ========= 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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 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/68070cf7c7207a871f4b1d11996aa2d49513b69a5f44d714545dfa50e6b1b2e20239c4e60870f92efae67055a59b728dbe683a1f930421d3a8f1ba205c8a8419", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/adb0b4cd98ce538c980cb21c275acb51c99d1b6775d45661ebd9ad4c4c572d1bda930ba525910c2a6c8c9aaaf73dcc8ece65bedd9a05fa97fa16b7d53da7201b"} build_url:https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/GrGXecxDQ-uwDLuhCoPh9g/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/68070cf7c7207a871f4b1d11996aa2d49513b69a5f44d714545dfa50e6b1b2e20239c4e60870f92efae67055a59b728dbe683a1f930421d3a8f1ba205c8a8419", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/adb0b4cd98ce538c980cb21c275acb51c99d1b6775d45661ebd9ad4c4c572d1bda930ba525910c2a6c8c9aaaf73dcc8ece65bedd9a05fa97fa16b7d53da7201b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-04 10:17:25.529101) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 10:17:25.529454) ========= 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=4dd8011cc2821079d74a932a000001ae-1457114090.320267-604175379 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004505 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-04 10:17:25.615347) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-04 10:17:25.615697) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-04 10:17:25.615995) ========= ========= Total master_lag: 0.27 =========