builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1456424891.96 results: success (0) buildid: 20160225080739 builduid: 178561873c884d39943c7486e36ff914 revision: 773911dc83da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:11.957807) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:11.958258) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:11.958564) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009020 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.000979) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.001283) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.040458) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.040771) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015777 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.090681) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.091026) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.091421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.091735) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 10:28: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% 79.4M=0s 2016-02-25 10:28:12 (79.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173156 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.278517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.279029) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.121599 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:12.414863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-25 10:28:12.415279) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 773911dc83da --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-beta --rev 773911dc83da --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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 10:28:12,503 Setting DEBUG logging. 2016-02-25 10:28:12,503 attempt 1/10 2016-02-25 10:28:12,503 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/773911dc83da?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 10:28:12,932 unpacking tar archive at: mozilla-beta-773911dc83da/testing/mozharness/ program finished with exit code 0 elapsedTime=1.170608 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-25 10:28:13.598244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:13.598580) ========= echo 773911dc83da in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'773911dc83da'] 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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 773911dc83da program finished with exit code 0 elapsedTime=0.004174 script_repo_revision: '773911dc83da' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:13.648427) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:13.648755) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 10:28:13.658686) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-02-25 10:28:13.659107) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:28:13 INFO - MultiFileLogger online at 20160225 10:28:13 in /builds/slave/test-pgo 10:28:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:28:13 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 10:28:13 INFO - {'append_to_log': False, 10:28:13 INFO - 'base_work_dir': '/builds/slave/test-pgo', 10:28:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:28:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 10:28:13 INFO - 'branch': 'Mozilla-Beta', 10:28:13 INFO - 'buildbot_json_path': 'buildprops.json', 10:28:13 INFO - 'config_files': ('talos/linux_config.py',), 10:28:13 INFO - 'default_actions': ('clobber', 10:28:13 INFO - 'read-buildbot-config', 10:28:13 INFO - 'download-and-extract', 10:28:13 INFO - 'populate-webroot', 10:28:13 INFO - 'create-virtualenv', 10:28:13 INFO - 'install', 10:28:13 INFO - 'run-tests'), 10:28:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:28:13 INFO - 'download_minidump_stackwalk': True, 10:28:13 INFO - 'download_symbols': 'ondemand', 10:28:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:28:13 INFO - 'tooltool.py': '/tools/tooltool.py', 10:28:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:28:13 INFO - '/tools/misc-python/virtualenv.py')}, 10:28:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:28:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:28:13 INFO - 'installer_path': 'installer.exe', 10:28:13 INFO - 'log_level': 'info', 10:28:13 INFO - 'log_name': 'talos', 10:28:13 INFO - 'log_to_console': True, 10:28:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:28:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:28:13 INFO - 'opt_config_files': (), 10:28:13 INFO - 'pip_index': False, 10:28:13 INFO - 'sps_profile': False, 10:28:13 INFO - 'sps_profile_interval': 0, 10:28:13 INFO - 'suite': 'dromaeojs', 10:28:13 INFO - 'system_bits': '32', 10:28:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:28:13 INFO - 'title': 'talos-linux64-ix-051', 10:28:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:28:13 INFO - 'use_talos_json': True, 10:28:13 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 10:28:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:28:13 INFO - 'work_dir': 'build'} 10:28:13 INFO - ##### 10:28:13 INFO - ##### Running clobber step. 10:28:13 INFO - ##### 10:28:13 INFO - Running pre-action listener: _resource_record_pre_action 10:28:13 INFO - Running main action method: clobber 10:28:13 INFO - rmtree: /builds/slave/test-pgo/build 10:28:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 10:28:14 INFO - Running post-action listener: _resource_record_post_action 10:28:14 INFO - ##### 10:28:14 INFO - ##### Running read-buildbot-config step. 10:28:14 INFO - ##### 10:28:14 INFO - Running pre-action listener: _resource_record_pre_action 10:28:14 INFO - Running main action method: read_buildbot_config 10:28:14 INFO - Using buildbot properties: 10:28:14 INFO - { 10:28:14 INFO - "properties": { 10:28:14 INFO - "buildnumber": 122, 10:28:14 INFO - "product": "firefox", 10:28:14 INFO - "script_repo_revision": "production", 10:28:14 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:28:14 INFO - "repository": "", 10:28:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:28:14 INFO - "buildid": "20160225080739", 10:28:14 INFO - "slavename": "talos-linux64-ix-051", 10:28:14 INFO - "pgo_build": "True", 10:28:14 INFO - "basedir": "/builds/slave/test-pgo", 10:28:14 INFO - "project": "", 10:28:14 INFO - "platform": "ubuntu64_hw", 10:28:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 10:28:14 INFO - "slavebuilddir": "test", 10:28:14 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:28:14 INFO - "branch": "mozilla-beta", 10:28:14 INFO - "repo_path": "releases/mozilla-beta", 10:28:14 INFO - "stage_platform": "linux64-pgo", 10:28:14 INFO - "builduid": "178561873c884d39943c7486e36ff914", 10:28:14 INFO - "revision": "773911dc83da" 10:28:14 INFO - }, 10:28:14 INFO - "sourcestamp": { 10:28:14 INFO - "repository": "", 10:28:14 INFO - "hasPatch": false, 10:28:14 INFO - "project": "", 10:28:14 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:28:14 INFO - "changes": [ 10:28:14 INFO - { 10:28:14 INFO - "category": null, 10:28:14 INFO - "files": [ 10:28:14 INFO - { 10:28:14 INFO - "url": null, 10:28:14 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2" 10:28:14 INFO - } 10:28:14 INFO - ], 10:28:14 INFO - "repository": "", 10:28:14 INFO - "rev": "773911dc83da", 10:28:14 INFO - "who": "sendchange", 10:28:14 INFO - "when": 1456424836, 10:28:14 INFO - "number": 7654280, 10:28:14 INFO - "comments": "Bug 1246641 - Occasionally execute an EndDraw command when command lists are used. r=jrmuizel, a=sylvestre", 10:28:14 INFO - "project": "", 10:28:14 INFO - "at": "Thu 25 Feb 2016 10:27:16", 10:28:14 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:28:14 INFO - "revlink": "", 10:28:14 INFO - "properties": [ 10:28:14 INFO - [ 10:28:14 INFO - "buildid", 10:28:14 INFO - "20160225080739", 10:28:14 INFO - "Change" 10:28:14 INFO - ], 10:28:14 INFO - [ 10:28:14 INFO - "builduid", 10:28:14 INFO - "178561873c884d39943c7486e36ff914", 10:28:14 INFO - "Change" 10:28:14 INFO - ], 10:28:14 INFO - [ 10:28:14 INFO - "pgo_build", 10:28:14 INFO - "True", 10:28:14 INFO - "Change" 10:28:14 INFO - ] 10:28:14 INFO - ], 10:28:14 INFO - "revision": "773911dc83da" 10:28:14 INFO - } 10:28:14 INFO - ], 10:28:14 INFO - "revision": "773911dc83da" 10:28:14 INFO - } 10:28:14 INFO - } 10:28:14 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2. 10:28:14 INFO - Running post-action listener: _resource_record_post_action 10:28:14 INFO - ##### 10:28:14 INFO - ##### Running download-and-extract step. 10:28:14 INFO - ##### 10:28:14 INFO - Running pre-action listener: _resource_record_pre_action 10:28:14 INFO - Running main action method: download_and_extract 10:28:14 INFO - mkdir: /builds/slave/test-pgo/build/tests 10:28: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')]} 10:28:14 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/test_packages.json 10:28:14 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 10:28:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 10:28:15 INFO - Downloaded 1270 bytes. 10:28:15 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 10:28:15 INFO - Using the following test package requirements: 10:28:15 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:28:15 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:28:15 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:28:15 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:28:15 INFO - u'jsshell-linux-x86_64.zip'], 10:28:15 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:28:15 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:28:15 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:28:15 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:28:15 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 10:28:15 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:28:15 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 10:28:15 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:28:15 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:28:15 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:28:15 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:28:15 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:28:15 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:28:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:28:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:28:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:28:15 INFO - Downloaded 21584475 bytes. 10:28:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:28:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:28:15 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:28:16 INFO - Return code: 0 10:28:16 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:28:16 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')]} 10:28:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:28:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:28:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:28:17 INFO - Downloaded 21584475 bytes. 10:28:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:28:17 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:28:17 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:28:18 INFO - Return code: 0 10:28:18 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')]} 10:28:18 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:28:18 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:28:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:28:21 INFO - Downloaded 11132038 bytes. 10:28:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 10:28:21 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:28:21 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 10:28:21 INFO - Return code: 0 10:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:28:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:28:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:28:24 INFO - Downloaded 57943945 bytes. 10:28:24 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:28:24 INFO - mkdir: /builds/slave/test-pgo/properties 10:28:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 10:28:24 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 10:28:24 INFO - Contents: 10:28:24 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:28:24 INFO - Running post-action listener: _resource_record_post_action 10:28:24 INFO - Running post-action listener: set_extra_try_arguments 10:28:24 INFO - ##### 10:28:24 INFO - ##### Running populate-webroot step. 10:28:24 INFO - ##### 10:28:24 INFO - Running pre-action listener: _resource_record_pre_action 10:28:24 INFO - Running main action method: populate_webroot 10:28:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:28:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:28:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:28:24 INFO - '--tppagecycles', 10:28:24 INFO - '7'], 10:28:24 INFO - 'tests': ['tsvgm']}}, 10:28:24 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:28:24 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:28:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:28:24 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:28:24 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:28:24 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:28:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:28:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:24 INFO - 'talos_options': ['--e10s'], 10:28:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:28:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:24 INFO - 'tests': ['damp', 'tps']}, 10:28:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:24 INFO - 'talos_options': ['--e10s'], 10:28:24 INFO - 'tests': ['damp', 'tps']}, 10:28:24 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:28:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:28:24 INFO - 'other': {'tests': ['a11yr', 10:28:24 INFO - 'ts_paint', 10:28:24 INFO - 'tpaint', 10:28:24 INFO - 'sessionrestore', 10:28:24 INFO - 'sessionrestore_no_auto_restore']}, 10:28:24 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:28:24 INFO - 'tests': ['a11yr', 10:28:24 INFO - 'ts_paint', 10:28:24 INFO - 'tpaint', 10:28:24 INFO - 'sessionrestore', 10:28:24 INFO - 'sessionrestore_no_auto_restore']}, 10:28:24 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:28:25 INFO - 'tests': ['a11yr', 10:28:25 INFO - 'ts_paint', 10:28:25 INFO - 'tpaint', 10:28:25 INFO - 'sessionrestore', 10:28:25 INFO - 'sessionrestore_no_auto_restore']}, 10:28:25 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:28:25 INFO - 'tests': ['a11yr', 10:28:25 INFO - 'ts_paint', 10:28:25 INFO - 'tpaint', 10:28:25 INFO - 'sessionrestore', 10:28:25 INFO - 'sessionrestore_no_auto_restore']}, 10:28:25 INFO - 'other_l64': {'tests': ['a11yr', 10:28:25 INFO - 'ts_paint', 10:28:25 INFO - 'tpaint', 10:28:25 INFO - 'sessionrestore', 10:28:25 INFO - 'sessionrestore_no_auto_restore']}, 10:28:25 INFO - 'other_nol64': {'tests': ['a11yr', 10:28:25 INFO - 'ts_paint', 10:28:25 INFO - 'tpaint', 10:28:25 INFO - 'sessionrestore', 10:28:25 INFO - 'sessionrestore_no_auto_restore']}, 10:28:25 INFO - 'svgr': {'tests': ['tsvgx', 10:28:25 INFO - 'tsvgr_opacity', 10:28:25 INFO - 'tart', 10:28:25 INFO - 'tscrollx', 10:28:25 INFO - 'cart']}, 10:28:25 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:28:25 INFO - 'tests': ['tsvgx', 10:28:25 INFO - 'tsvgr_opacity', 10:28:25 INFO - 'tart', 10:28:25 INFO - 'tscrollx', 10:28:25 INFO - 'cart']}, 10:28:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:25 INFO - 'tests': ['tp5o']}, 10:28:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:25 INFO - 'talos_options': ['--e10s'], 10:28:25 INFO - 'tests': ['tp5o']}, 10:28:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:25 INFO - 'talos_options': ['--xperf_path', 10:28:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:28:25 INFO - 'tests': ['tp5n']}, 10:28:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:28:25 INFO - 'talos_options': ['--e10s', 10:28:25 INFO - '--xperf_path', 10:28:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:28:25 INFO - 'tests': ['tp5n']}}, 10:28:25 INFO - 'talos.zip': {'path': '', 10:28:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:28:25 INFO - Running post-action listener: _resource_record_post_action 10:28:25 INFO - ##### 10:28:25 INFO - ##### Running create-virtualenv step. 10:28:25 INFO - ##### 10:28:25 INFO - Running pre-action listener: _resource_record_pre_action 10:28:25 INFO - Running main action method: create_virtualenv 10:28:25 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 10:28:25 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 10:28:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 10:28:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:28:25 INFO - Using real prefix '/usr' 10:28:25 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 10:28:25 INFO - Installing distribute.............................................................................................................................................................................................done. 10:28:28 INFO - Installing pip.................done. 10:28:28 INFO - Return code: 0 10:28:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 10:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:28 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 10:28:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:28:28 INFO - Using env: {'DISPLAY': ':0', 10:28:28 INFO - 'HOME': '/home/cltbld', 10:28:28 INFO - 'LANG': 'en_US.UTF-8', 10:28:28 INFO - 'LANGUAGE': 'en_US:en', 10:28:28 INFO - 'LOGNAME': 'cltbld', 10:28:28 INFO - 'MAIL': '/var/mail/cltbld', 10:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:28 INFO - 'MOZ_NO_REMOTE': '1', 10:28:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:28 INFO - 'NO_EM_RESTART': '1', 10:28:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:28 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:28 INFO - 'SHELL': '/bin/bash', 10:28:28 INFO - 'SHLVL': '1', 10:28:28 INFO - 'TERM': 'linux', 10:28:28 INFO - 'TMOUT': '86400', 10:28:28 INFO - 'USER': 'cltbld', 10:28:28 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:28 INFO - '_': '/tools/buildbot/bin/python'} 10:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:29 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 10:28:29 INFO - Cleaning up... 10:28:29 INFO - Return code: 0 10:28:29 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 10:28: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')]} 10:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:29 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 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:29 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 10:28:29 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 10:28:29 INFO - Using env: {'DISPLAY': ':0', 10:28:29 INFO - 'HOME': '/home/cltbld', 10:28:29 INFO - 'LANG': 'en_US.UTF-8', 10:28:29 INFO - 'LANGUAGE': 'en_US:en', 10:28:29 INFO - 'LOGNAME': 'cltbld', 10:28:29 INFO - 'MAIL': '/var/mail/cltbld', 10:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:29 INFO - 'MOZ_NO_REMOTE': '1', 10:28:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:29 INFO - 'NO_EM_RESTART': '1', 10:28:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:29 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:29 INFO - 'SHELL': '/bin/bash', 10:28:29 INFO - 'SHLVL': '1', 10:28:29 INFO - 'TERM': 'linux', 10:28:29 INFO - 'TMOUT': '86400', 10:28:29 INFO - 'USER': 'cltbld', 10:28:29 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:29 INFO - '_': '/tools/buildbot/bin/python'} 10:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:29 INFO - Downloading/unpacking psutil>=0.7.1 10:28:29 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 10:28:29 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 10:28:29 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 10:28:29 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 10:28:29 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 10:28:29 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 10:28:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 10:28:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:28:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:28:31 INFO - Installing collected packages: psutil 10:28:31 INFO - Running setup.py install for psutil 10:28:31 INFO - building 'psutil._psutil_linux' extension 10:28:31 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 10:28:31 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 10:28:31 INFO - building 'psutil._psutil_posix' extension 10:28:31 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 10:28:31 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 10:28:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:28:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:28:31 INFO - Successfully installed psutil 10:28:31 INFO - Cleaning up... 10:28:32 INFO - Return code: 0 10:28:32 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:28:32 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')]} 10:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:32 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')]} 10:28:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:32 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 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:32 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 10:28:32 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 10:28:32 INFO - Using env: {'DISPLAY': ':0', 10:28:32 INFO - 'HOME': '/home/cltbld', 10:28:32 INFO - 'LANG': 'en_US.UTF-8', 10:28:32 INFO - 'LANGUAGE': 'en_US:en', 10:28:32 INFO - 'LOGNAME': 'cltbld', 10:28:32 INFO - 'MAIL': '/var/mail/cltbld', 10:28:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:32 INFO - 'MOZ_NO_REMOTE': '1', 10:28:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:32 INFO - 'NO_EM_RESTART': '1', 10:28:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:32 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:32 INFO - 'SHELL': '/bin/bash', 10:28:32 INFO - 'SHLVL': '1', 10:28:32 INFO - 'TERM': 'linux', 10:28:32 INFO - 'TMOUT': '86400', 10:28:32 INFO - 'USER': 'cltbld', 10:28:32 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:32 INFO - '_': '/tools/buildbot/bin/python'} 10:28:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:32 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:28:32 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 10:28:32 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 10:28:32 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 10:28:32 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 10:28:32 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 10:28:32 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 10:28:34 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:28:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:28:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:28:34 INFO - Installing collected packages: mozsystemmonitor 10:28:34 INFO - Running setup.py install for mozsystemmonitor 10:28:34 INFO - Successfully installed mozsystemmonitor 10:28:34 INFO - Cleaning up... 10:28:34 INFO - Return code: 0 10:28:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:34 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 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:34 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 10:28:34 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 10:28:34 INFO - Using env: {'DISPLAY': ':0', 10:28:34 INFO - 'HOME': '/home/cltbld', 10:28:34 INFO - 'LANG': 'en_US.UTF-8', 10:28:34 INFO - 'LANGUAGE': 'en_US:en', 10:28:34 INFO - 'LOGNAME': 'cltbld', 10:28:34 INFO - 'MAIL': '/var/mail/cltbld', 10:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:34 INFO - 'MOZ_NO_REMOTE': '1', 10:28:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:34 INFO - 'NO_EM_RESTART': '1', 10:28:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:34 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:34 INFO - 'SHELL': '/bin/bash', 10:28:34 INFO - 'SHLVL': '1', 10:28:34 INFO - 'TERM': 'linux', 10:28:34 INFO - 'TMOUT': '86400', 10:28:34 INFO - 'USER': 'cltbld', 10:28:34 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:34 INFO - '_': '/tools/buildbot/bin/python'} 10:28:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:34 INFO - Downloading/unpacking blobuploader==1.2.4 10:28:34 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 10:28:34 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 10:28:34 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 10:28:34 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 10:28:34 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 10:28:34 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 10:28:36 INFO - Downloading blobuploader-1.2.4.tar.gz 10:28:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:28:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:28:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28:36 INFO - Downloading docopt-0.6.1.tar.gz 10:28:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:28:37 INFO - Installing collected packages: blobuploader, requests, docopt 10:28:37 INFO - Running setup.py install for blobuploader 10:28:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:28:37 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:28:37 INFO - Running setup.py install for requests 10:28:37 INFO - Running setup.py install for docopt 10:28:37 INFO - Successfully installed blobuploader requests docopt 10:28:37 INFO - Cleaning up... 10:28:37 INFO - Return code: 0 10:28:37 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:37 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 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:37 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 10:28:37 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 10:28:37 INFO - Using env: {'DISPLAY': ':0', 10:28:37 INFO - 'HOME': '/home/cltbld', 10:28:37 INFO - 'LANG': 'en_US.UTF-8', 10:28:37 INFO - 'LANGUAGE': 'en_US:en', 10:28:37 INFO - 'LOGNAME': 'cltbld', 10:28:37 INFO - 'MAIL': '/var/mail/cltbld', 10:28:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:37 INFO - 'MOZ_NO_REMOTE': '1', 10:28:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:37 INFO - 'NO_EM_RESTART': '1', 10:28:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:37 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:37 INFO - 'SHELL': '/bin/bash', 10:28:37 INFO - 'SHLVL': '1', 10:28:37 INFO - 'TERM': 'linux', 10:28:37 INFO - 'TMOUT': '86400', 10:28:37 INFO - 'USER': 'cltbld', 10:28:37 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:37 INFO - '_': '/tools/buildbot/bin/python'} 10:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:28:37 INFO - Running setup.py (path:/tmp/pip-JvC6zE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:28:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:28:37 INFO - Running setup.py (path:/tmp/pip-89ixT5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:28:38 INFO - Running setup.py (path:/tmp/pip-ctn2j6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:28:38 INFO - Running setup.py (path:/tmp/pip-VdJGWy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:28:38 INFO - Running setup.py (path:/tmp/pip-i1wGJu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:28:38 INFO - Running setup.py (path:/tmp/pip-LyRcoD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:28:38 INFO - Running setup.py (path:/tmp/pip-xbYkp5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:28:38 INFO - Running setup.py (path:/tmp/pip-EJBbyd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:28:38 INFO - Running setup.py (path:/tmp/pip-Mgqm6Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:28:38 INFO - Running setup.py (path:/tmp/pip-A925ae-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:28:38 INFO - Running setup.py (path:/tmp/pip-atWea2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:28:38 INFO - Running setup.py (path:/tmp/pip-mnuyig-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:28:38 INFO - Running setup.py (path:/tmp/pip-L7n_GW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:28:38 INFO - Running setup.py (path:/tmp/pip-kkxujH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:28:38 INFO - Running setup.py (path:/tmp/pip-yOSsgF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:28:38 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:28:38 INFO - Running setup.py (path:/tmp/pip-hQWJi8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:28:39 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:28:39 INFO - Running setup.py (path:/tmp/pip-azgjoc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:28:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:28:39 INFO - Running setup.py install for manifestparser 10:28:39 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Running setup.py install for mozcrash 10:28:39 INFO - Running setup.py install for mozdebug 10:28:39 INFO - Running setup.py install for mozdevice 10:28:39 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Running setup.py install for mozfile 10:28:39 INFO - Running setup.py install for mozhttpd 10:28:39 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Running setup.py install for mozinfo 10:28:39 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Running setup.py install for mozInstall 10:28:39 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:28:39 INFO - Running setup.py install for mozleak 10:28:39 INFO - Running setup.py install for mozlog 10:28:40 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:28:40 INFO - Running setup.py install for moznetwork 10:28:40 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:28:40 INFO - Running setup.py install for mozprocess 10:28:40 INFO - Running setup.py install for mozprofile 10:28:40 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:28:40 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:28:40 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:28:40 INFO - Running setup.py install for mozrunner 10:28:40 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:28:40 INFO - Running setup.py install for mozscreenshot 10:28:40 INFO - Running setup.py install for moztest 10:28:40 INFO - Running setup.py install for mozversion 10:28:40 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:28:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:28:40 INFO - Cleaning up... 10:28:40 INFO - Return code: 0 10:28:40 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:28: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')]} 10:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:40 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 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:40 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 10:28:40 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 10:28:40 INFO - Using env: {'DISPLAY': ':0', 10:28:40 INFO - 'HOME': '/home/cltbld', 10:28:40 INFO - 'LANG': 'en_US.UTF-8', 10:28:40 INFO - 'LANGUAGE': 'en_US:en', 10:28:40 INFO - 'LOGNAME': 'cltbld', 10:28:40 INFO - 'MAIL': '/var/mail/cltbld', 10:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:40 INFO - 'MOZ_NO_REMOTE': '1', 10:28:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:40 INFO - 'NO_EM_RESTART': '1', 10:28:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:40 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:40 INFO - 'SHELL': '/bin/bash', 10:28:40 INFO - 'SHLVL': '1', 10:28:40 INFO - 'TERM': 'linux', 10:28:40 INFO - 'TMOUT': '86400', 10:28:40 INFO - 'USER': 'cltbld', 10:28:40 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:40 INFO - '_': '/tools/buildbot/bin/python'} 10:28:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:28:41 INFO - Running setup.py (path:/tmp/pip-9UWqUZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:28:41 INFO - Running setup.py (path:/tmp/pip-7QHOGg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:28:41 INFO - Running setup.py (path:/tmp/pip-Ra0iNd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:28:41 INFO - Running setup.py (path:/tmp/pip-wvyaZ1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:28:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:28:41 INFO - Running setup.py (path:/tmp/pip-okYzMI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:28:41 INFO - Running setup.py (path:/tmp/pip-eZpOZQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:28:41 INFO - Running setup.py (path:/tmp/pip-23tMCU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:28:41 INFO - Running setup.py (path:/tmp/pip-t8rMSA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:28:41 INFO - Running setup.py (path:/tmp/pip-WhJHAq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:28:41 INFO - Running setup.py (path:/tmp/pip-OZHWaR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:28:41 INFO - Running setup.py (path:/tmp/pip-G2_p0V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:28:41 INFO - Running setup.py (path:/tmp/pip-jEzv9I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:28:41 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)) 10:28:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:28:41 INFO - Running setup.py (path:/tmp/pip-7GGAz9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:28:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 10:28:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:28:42 INFO - Running setup.py (path:/tmp/pip-QlTMuN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:28:42 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)) 10:28:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:28:42 INFO - Running setup.py (path:/tmp/pip-4JGgka-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:28:42 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)) 10:28:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:28:42 INFO - Running setup.py (path:/tmp/pip-lr47rF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:28:42 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)) 10:28:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:28:42 INFO - Running setup.py (path:/tmp/pip-CROKru-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:28:42 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)) 10:28:42 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)) 10:28:42 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)) 10:28:42 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)) 10:28:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:28:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:28:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28: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 10:28:44 INFO - Downloading blessings-1.6.tar.gz 10:28:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:28:44 INFO - Installing collected packages: blessings 10:28:44 INFO - Running setup.py install for blessings 10:28:44 INFO - Successfully installed blessings 10:28:44 INFO - Cleaning up... 10:28:44 INFO - Return code: 0 10:28:44 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:28:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:28:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:28:44 INFO - Reading from file tmpfile_stdout 10:28:44 INFO - Current package versions: 10:28:44 INFO - argparse == 1.2.1 10:28:44 INFO - blessings == 1.6 10:28:44 INFO - blobuploader == 1.2.4 10:28:44 INFO - docopt == 0.6.1 10:28:44 INFO - manifestparser == 1.1 10:28:44 INFO - mozInstall == 1.12 10:28:44 INFO - mozcrash == 0.16 10:28:44 INFO - mozdebug == 0.1 10:28:44 INFO - mozdevice == 0.47 10:28:44 INFO - mozfile == 1.2 10:28:44 INFO - mozhttpd == 0.7 10:28:44 INFO - mozinfo == 0.9 10:28:44 INFO - mozleak == 0.1 10:28:44 INFO - mozlog == 3.1 10:28:44 INFO - moznetwork == 0.27 10:28:44 INFO - mozprocess == 0.22 10:28:44 INFO - mozprofile == 0.27 10:28:44 INFO - mozrunner == 6.11 10:28:44 INFO - mozscreenshot == 0.1 10:28:44 INFO - mozsystemmonitor == 0.0 10:28:44 INFO - moztest == 0.7 10:28:44 INFO - mozversion == 1.4 10:28:44 INFO - psutil == 3.1.1 10:28:44 INFO - requests == 1.2.3 10:28:44 INFO - wsgiref == 0.1.2 10:28:44 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:28: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')]} 10:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:44 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 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:44 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 10:28:44 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 10:28:44 INFO - Using env: {'DISPLAY': ':0', 10:28:44 INFO - 'HOME': '/home/cltbld', 10:28:44 INFO - 'LANG': 'en_US.UTF-8', 10:28:44 INFO - 'LANGUAGE': 'en_US:en', 10:28:44 INFO - 'LOGNAME': 'cltbld', 10:28:44 INFO - 'MAIL': '/var/mail/cltbld', 10:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:44 INFO - 'MOZ_NO_REMOTE': '1', 10:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:44 INFO - 'NO_EM_RESTART': '1', 10:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:44 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:44 INFO - 'SHELL': '/bin/bash', 10:28:44 INFO - 'SHLVL': '1', 10:28:44 INFO - 'TERM': 'linux', 10:28:44 INFO - 'TMOUT': '86400', 10:28:44 INFO - 'USER': 'cltbld', 10:28:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:44 INFO - '_': '/tools/buildbot/bin/python'} 10:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:44 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 1)) 10:28:44 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 2)) 10:28:44 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 3)) 10:28:44 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 4)) 10:28:44 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 5)) 10:28:44 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 6)) 10:28:44 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 7)) 10:28:44 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 8)) 10:28:44 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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:28:44 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 3)) 10:28:44 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.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:28:44 INFO - Cleaning up... 10:28:44 INFO - Return code: 0 10:28:44 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:28: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')]} 10:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 10:28:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:28:44 INFO - Using env: {'DISPLAY': ':0', 10:28:44 INFO - 'HOME': '/home/cltbld', 10:28:44 INFO - 'LANG': 'en_US.UTF-8', 10:28:44 INFO - 'LANGUAGE': 'en_US:en', 10:28:44 INFO - 'LOGNAME': 'cltbld', 10:28:44 INFO - 'MAIL': '/var/mail/cltbld', 10:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:44 INFO - 'MOZ_NO_REMOTE': '1', 10:28:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:44 INFO - 'NO_EM_RESTART': '1', 10:28:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:44 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:44 INFO - 'SHELL': '/bin/bash', 10:28:44 INFO - 'SHLVL': '1', 10:28:44 INFO - 'TERM': 'linux', 10:28:44 INFO - 'TMOUT': '86400', 10:28:44 INFO - 'USER': 'cltbld', 10:28:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:44 INFO - '_': '/tools/buildbot/bin/python'} 10:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:45 INFO - Downloading/unpacking jsonschema 10:28:45 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 10:28:45 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 10:28:45 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 10:28:45 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 10:28:45 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 10:28:45 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 10:28:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:28:46 INFO - Downloading/unpacking functools32 (from jsonschema) 10:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:47 INFO - Downloading functools32-3.2.3-2.tar.gz 10:28:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:28:47 INFO - warning: no files found matching '*.txt' 10:28:47 INFO - no previously-included directories found matching 'build' 10:28:47 INFO - no previously-included directories found matching 'dist' 10:28:47 INFO - no previously-included directories found matching '.git*' 10:28:47 INFO - Installing collected packages: jsonschema, functools32 10:28:47 INFO - Running setup.py install for functools32 10:28:47 INFO - warning: no files found matching '*.txt' 10:28:47 INFO - no previously-included directories found matching 'build' 10:28:47 INFO - no previously-included directories found matching 'dist' 10:28:47 INFO - no previously-included directories found matching '.git*' 10:28:47 INFO - Successfully installed jsonschema functools32 10:28:47 INFO - Cleaning up... 10:28:47 INFO - Return code: 0 10:28:47 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:28: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')]} 10:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28: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')]} 10:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:47 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 0x7f79b33fef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21598a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2226600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', '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 10:28:47 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 10:28:47 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 10:28:47 INFO - Using env: {'DISPLAY': ':0', 10:28:47 INFO - 'HOME': '/home/cltbld', 10:28:47 INFO - 'LANG': 'en_US.UTF-8', 10:28:47 INFO - 'LANGUAGE': 'en_US:en', 10:28:47 INFO - 'LOGNAME': 'cltbld', 10:28:47 INFO - 'MAIL': '/var/mail/cltbld', 10:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:47 INFO - 'MOZ_NO_REMOTE': '1', 10:28:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:47 INFO - 'NO_EM_RESTART': '1', 10:28:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:28:47 INFO - 'PWD': '/builds/slave/test-pgo', 10:28:47 INFO - 'SHELL': '/bin/bash', 10:28:47 INFO - 'SHLVL': '1', 10:28:47 INFO - 'TERM': 'linux', 10:28:47 INFO - 'TMOUT': '86400', 10:28:47 INFO - 'USER': 'cltbld', 10:28:47 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:47 INFO - '_': '/tools/buildbot/bin/python'} 10:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:47 INFO - Downloading/unpacking flake8 10:28:47 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 10:28:47 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 10:28:47 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 10:28:47 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 10:28:47 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 10:28:47 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 10:28:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:28:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:28:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:49 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 10:28:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:28:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:28:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:28:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:28:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:28:50 INFO - no previously-included directories found matching 'docs/_build' 10:28:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:28:50 INFO - Running setup.py install for pep8 10:28:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:28:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:28:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:28:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:28:50 INFO - no previously-included directories found matching 'docs/_build' 10:28:50 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:28:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:28:50 INFO - Cleaning up... 10:28:50 INFO - Return code: 0 10:28:50 INFO - Running post-action listener: _resource_record_post_action 10:28:50 INFO - Running post-action listener: _start_resource_monitoring 10:28:50 INFO - Starting resource monitoring. 10:28:50 INFO - ##### 10:28:50 INFO - ##### Running install step. 10:28:50 INFO - ##### 10:28:50 INFO - Running pre-action listener: _resource_record_pre_action 10:28:50 INFO - Running main action method: install 10:28:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:28:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:28:50 INFO - Reading from file tmpfile_stdout 10:28:50 INFO - Detecting whether we're running mozinstall >=1.0... 10:28:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:28:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:28:50 INFO - Reading from file tmpfile_stdout 10:28:50 INFO - Output received: 10:28:50 INFO - Usage: mozinstall [options] installer 10:28:50 INFO - Options: 10:28:50 INFO - -h, --help show this help message and exit 10:28:50 INFO - -d DEST, --destination=DEST 10:28:50 INFO - Directory to install application into. [default: 10:28:50 INFO - "/builds/slave/test-pgo"] 10:28:50 INFO - --app=APP Application being installed. [default: firefox] 10:28:50 INFO - mkdir: /builds/slave/test-pgo/build/application 10:28:50 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 10:28:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 10:29:05 INFO - Reading from file tmpfile_stdout 10:29:05 INFO - Output received: 10:29:05 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:29:05 INFO - Running post-action listener: _resource_record_post_action 10:29:05 INFO - ##### 10:29:05 INFO - ##### Running run-tests step. 10:29:05 INFO - ##### 10:29:05 INFO - Running pre-action listener: _resource_record_pre_action 10:29:05 INFO - Running main action method: run_tests 10:29:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:29:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:29:05 INFO - Python 2.7.3 10:29:05 INFO - Return code: 0 10:29:05 INFO - grabbing minidump binary from tooltool 10:29:05 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')]} 10:29:05 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 0x22b5350>, '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 0x22b6250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b66e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:29:05 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 10:29:05 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 10:29:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:29:05 INFO - Return code: 0 10:29:05 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:29:05 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:29:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:29:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:29:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:29:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:29:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:29:06 INFO - Return code: 0 10:29:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:29:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --webServer localhost 10:29:06 INFO - Using env: {'DISPLAY': ':0', 10:29:06 INFO - 'HOME': '/home/cltbld', 10:29:06 INFO - 'LANG': 'en_US.UTF-8', 10:29:06 INFO - 'LANGUAGE': 'en_US:en', 10:29:06 INFO - 'LOGNAME': 'cltbld', 10:29:06 INFO - 'MAIL': '/var/mail/cltbld', 10:29:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:29:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:29:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:29:06 INFO - 'MOZ_NO_REMOTE': '1', 10:29:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:29:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:29:06 INFO - 'NO_EM_RESTART': '1', 10:29:06 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:29:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:29:06 INFO - 'PWD': '/builds/slave/test-pgo', 10:29:06 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:29:06 INFO - 'SHELL': '/bin/bash', 10:29:06 INFO - 'SHLVL': '1', 10:29:06 INFO - 'TERM': 'linux', 10:29:06 INFO - 'TMOUT': '86400', 10:29:06 INFO - 'USER': 'cltbld', 10:29:06 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344', 10:29:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:29:06 INFO - '_': '/tools/buildbot/bin/python'} 10:29:06 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--webServer', 'localhost'] with output_timeout 3600 10:29:06 INFO - mozversion INFO | application_buildid: 20160225080739 10:29:06 INFO - mozversion INFO | application_changeset: 773911dc83dae3f11f6b6a4a95813b5b24c2fd41 10:29:06 INFO - mozversion INFO | application_display_name: Nightly 10:29:06 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:29:06 INFO - mozversion INFO | application_name: Firefox 10:29:06 INFO - mozversion INFO | application_remotingname: firefox 10:29:06 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:29:06 INFO - mozversion INFO | application_vendor: Mozilla 10:29:06 INFO - mozversion INFO | application_version: 45.0 10:29:06 INFO - mozversion INFO | platform_buildid: 20160225080739 10:29:06 INFO - mozversion INFO | platform_changeset: 773911dc83dae3f11f6b6a4a95813b5b24c2fd41 10:29:06 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:29:06 INFO - mozversion INFO | platform_version: 45.0 10:29:06 INFO - 2016-02-25 10:29:06,516 DEBUG : using testdate: 1456424946 10:29:06 INFO - 2016-02-25 10:29:06,516 DEBUG : actual date: 1456424946 10:29:06 INFO - 2016-02-25 10:29:06,525 INFO : starting webserver on 'localhost:49143' 10:29:06 INFO - 2016-02-25 10:29:06,526 INFO : Starting test suite talos-linux64-ix-051 10:29:06 INFO - 2016-02-25 10:29:06,526 INFO : Starting test dromaeo_css 10:29:06 INFO - 2016-02-25 10:29:06,527 DEBUG : operating with platform_type : linux_ 10:29:06 INFO - 2016-02-25 10:29:06,527 INFO : Initialising browser for dromaeo_css test... 10:29:06 INFO - 2016-02-25 10:29:06,542 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3Olqja/profile http://localhost:49143/getInfo.html 10:29:10 INFO - 2016-02-25 10:29:10,946 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:29:10 INFO - 2016-02-25 10:29:10,946 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:29:10 INFO - 2016-02-25 10:29:10,946 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:29:10 INFO - 2016-02-25 10:29:10,947 DEBUG : BROWSER_OUTPUT: __metrics 10:29:11 INFO - 2016-02-25 10:29:11,104 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:29:12 INFO - 2016-02-25 10:29:12,021 INFO : Browser initialized. 10:29:12 INFO - 2016-02-25 10:29:12,022 INFO : Running cycle 1/1 for dromaeo_css test... 10:29:12 INFO - 2016-02-25 10:29:12,022 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3Olqja/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:29:23 INFO - 2016-02-25 10:29:23,626 DEBUG : BROWSER_OUTPUT: RSS: Main: 134012928 10:29:23 INFO - 2016-02-25 10:29:23,626 DEBUG : BROWSER_OUTPUT: 10:30:14 INFO - 2016-02-25 10:30:14,454 DEBUG : BROWSER_OUTPUT: 1456425014452 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:32:22 INFO - 2016-02-25 10:32:22,817 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49143/tests/dromaeo/cssquery-ext.html) 10:32:23 INFO - 2016-02-25 10:32:23,887 DEBUG : BROWSER_OUTPUT: RSS: Main: 242954240 10:32:23 INFO - 2016-02-25 10:32:23,887 DEBUG : BROWSER_OUTPUT: 10:35:25 INFO - 2016-02-25 10:35:25,990 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/dromaeo/cssquery-ext.html (next: http://localhost:49143/tests/dromaeo/cssquery-jquery.html) 10:35:26 INFO - 2016-02-25 10:35:26,557 DEBUG : BROWSER_OUTPUT: RSS: Main: 215130112 10:35:26 INFO - 2016-02-25 10:35:26,557 DEBUG : BROWSER_OUTPUT: 10:38:28 INFO - 2016-02-25 10:38:28,284 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49143/tests/dromaeo/cssquery-mootools.html) 10:38:28 INFO - 2016-02-25 10:38:28,847 DEBUG : BROWSER_OUTPUT: RSS: Main: 201928704 10:38:28 INFO - 2016-02-25 10:38:28,847 DEBUG : BROWSER_OUTPUT: 10:41:30 INFO - 2016-02-25 10:41:30,525 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49143/tests/dromaeo/cssquery-prototype.html) 10:41:31 INFO - 2016-02-25 10:41:31,093 DEBUG : BROWSER_OUTPUT: RSS: Main: 198574080 10:41:31 INFO - 2016-02-25 10:41:31,093 DEBUG : BROWSER_OUTPUT: 10:44:33 INFO - 2016-02-25 10:44:33,535 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49143/tests/dromaeo/cssquery-yui.html) 10:44:34 INFO - 2016-02-25 10:44:34,101 DEBUG : BROWSER_OUTPUT: RSS: Main: 199331840 10:44:34 INFO - 2016-02-25 10:44:34,102 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,201 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/dromaeo/cssquery-yui.html (next: http://localhost:49143/tests/dromaeo/cssquery-dojo.html) 10:47:36 INFO - 2016-02-25 10:47:36,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 242204672 10:47:36 INFO - 2016-02-25 10:47:36,451 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,452 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:47:36 INFO - 2016-02-25 10:47:36,452 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:47:36 INFO - 2016-02-25 10:47:36,452 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:47:36 INFO - 2016-02-25 10:47:36,452 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:47:36 INFO - 2016-02-25 10:47:36,453 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3270.7056364927157;3336.3494118646845;3354.731621470283;3357.060023193507;3379.476181191915;5442.183672449135;5450.536704380127;5454.754536045877;5461.15352120421;5489;5454.072807622703;5471.316085489314;5479.287692599956;5495.230667706524;5495.752691128899;4888.511148885119;4975.477574854643;5013.824516141935;5040.546350828425;5071.923921141186;4920.3111564381015;4977.00459908018;4981.875453113683;4982.252662100677;4986.077575648498;4911.5088491150955;4954.653174277802;5006.874828129289;5010.423801262865;5014.6991180529285;5061.089003979282;5104.412992505872;5107.259447380109;5111.69329840209;5137.51193636604;4976.552110310089;5022.899542009176;5068.062408454448;5069.873253168664;5073.746312684351;5152.995165942642;5175.974120129413;5242.318498595197;5302.681839089667;5304.814331498379;5783.652980821164;5806.82579522615;5833.387494313079;5836.066229403275;5842.006858834008;5700.515456186229;5887.470127688529;5892.587518873681;5898.292204935436;5902.055671092605;5389.67661940281;5408.972955135238;5473.370562385297;5481.5340696041285;5505.862353441156;5955.285365756137;5956.315023772322;5958.553108516879;5959.880802383928;5962;5547.362053363834;5693.743781529841;5791.478766910998;5793.333766616809;5799.536037117105;5737.5983681141915;5840.649561026352;5849.064149736022;5874.118882167709;5882.176495290662;5850.765969361263;5883.529317654577;5887.970560147171;5889.705514724246;5890.175375447441;5756.481916627524;5882.529397648178;5892.646441213456;5899.793507227227;5900.586958912835;4470.083632855257;4511.593956543927;4512.075024619945;4514.6388288936805;4517.232070548015;3990.6009399059863;4043.4743483347565;4075.5924407559005;4079.3880917862557;4096.426500289962;5620.831375058755;5690.089585666274;5697.914531282034;5701.458361455655;5702.173184888251;5892.911606325909;5917.497012753965;5947.851303717355;5950.077737950624;5952.226210592682;5438.5377242934;5502.642328248651;5570.108782594765;5593.216949627055;5625.690587017753;5074.23886417033;5087.771050302746;5093.898122037538;5097.286379906816;5139.357580302462;5964.463198312173;6025.969870150709;6027.57806953509;6029.517638588903;6033.78881739137;5754.482096611325;5823.970880145572;5833.82498525045;5839.0949402842625;5840.269966254218;6025.849353766015;6029.276486821434;6041.75832966694;6042.425969532887;6056.454919057306;5648.717564121695;5759.625624334405;5794.275715535558;5797;5798.5651076168615;5840.065589505659;5957.314908785545;5966.224390829165;5966.463018328372;5980.760769569169;5718.399568045462;5723.42765723441;5724.284464441945;5724.914126288026;5737.713114344183;5579.609427340048;5621.578381621476;5724.971375143265;5732.598718089694;5742.59801813869;5565.7217139143695;5600.327960644586;5635.690037048002;5662.688552129673;5678.574106941914;5779.8555036124435;5787.450192231731;5801.796937107181;5804.100364443518;5809.24479817621;5504.532114770129;5597.692126933058;5687.090065589486;5691.26013618227;5694.316681998187 10:47:36 INFO - 2016-02-25 10:47:36,457 DEBUG : BROWSER_OUTPUT: |1;ext.html;576.1501784867319;606.0999415867437;616.5622408090258;621.8445388652834;622.9813105606834;31626.525602115988;40271.19457610849;40341.991450213725;40346;40423;45284.320735189;45654;45689.08621827562;45759.313610295874;45919;8185.631646575888;8319.417640765148;9073.546322683873;9257.16685498305;10451.477426128702;10626.734331641694;10792.676219713421;10801.243912926098;10810.837837432446;10816.78366432671;10705.518251678654;10745.301555398913;10828.675139745781;10831.891681083167;10840;10739.78520429591;10831.241813073088;10843.07833834125;10859.67420977368;10860.6198783043;10570.15438764897;10689.893101068967;10695.625653102104;10712.946435267851;10722.142228621691;41711.582884171075;41896.37155442671;42022.579774202175;42157.57842421567;42205.57794422047;3318.8340582970995;3383.915402114942;3578.141246100943;3718.4050551911564;4111.629953304187;29959.251018724488;30431.39137217243;30463.847680761675;30495;31100;3632.527771389729;3835.5972622874474;3850.3261929162286;3855.9807200964096;3870.1292209252974;300473;302041.48979255185;302447;303719;304061;59034;60089.699551501966;62765.68617156885;62820.05769913455;68158;63920;64475.35524644787;64537;64574.35425645777;64784.35215647877;15640.139792311533;15717.21413929299;15806.446774362843;15817.68364632724;15821.367145314058;12178.269303841798;12377.62867113988;12384.133110682345;12386.690332741753;12387.62837114888;20988.89505552493;21506.56986860254;21609.891950540146;21646.458838528848;21658.458538536663;8480.406371553932;8560.357973151915;8611.397202195787;8673.566321683891;8716.956415217883;1059.4967390489455;1129.1362107987395;1140.686311264409;1144.8397224388593;1148.9831499123336;29147.271318217216;29352.412951740847;29986.250343741143;30024.099277021724;33595.16012099717;1075.8924107589335;1089.4988305379457;1100.5927806711409;1166.7491489329834;1175.735459521601;13615.931920340336;14753.262336883328;14842.109473431628;16553.586160346087;17180.91409542944;427990;431237;432798;434023;434584;56489.71755141198;57424.42575574274;57457;57531;57585.71207144021;1070.4861666400172;1369.9589012329645;1458.9854101459061;1471.801306823565;1565.412970136199;29898.402031959675;30160.24599384945;34238.82880585581;34983.475247870796;35181.29637407289;53995.46004539904;54719.72640136933;54728;54743;54776.17835732387;311648;312902;314186;314205;314860;45267.320990185835;46958;47091;47581.524184759095;48307.758461208876;45814;45992.7700361496;46721;47069.29396058993;47090.058198836516;38486.42270365891;38515.229695406495;38986.80506597449;39243.60756392399;40143;38657.613423865405;38975.415368768925;39208.411873821344;39293.41059884047;39507.209855802146;37682.246355072195;38260.808695956344;38331.808340958116;38711.419328710654;38831.61168388393;33999.66000340064;34540.827295864365;34962.47556286607;35211;35228.295434091684;26740.7325926746;26785.464290713688;26822;27122.32194195161;27254.593175853017 10:47:36 INFO - 2016-02-25 10:47:36,457 DEBUG : BROWSER_OUTPUT: |2;jquery.html;360.4989065199375;363.64726215570903;364.29690696954884;369.40525753536804;371.21858487883;14637.707245855077;15078.547643570682;17091.82908170918;17632.294708211673;18121.184546695382;16603.41888033919;16708.164591770423;16934.576635584115;17591.208395622194;17619;5505.614606977503;5659.688717120557;5661.943380566183;5663.008973429654;5666.121751128561;4215.178040282146;4296.011917259032;5564.360098588653;5845.620034697752;6141.416565426277;6026.216591843055;6042.728077236535;6064.272287325527;6088.782243551307;6093.78671746489;5293.9735301323635;5410.97294513525;5419.512243898029;5432.7012014339;5434.184872269171;5123.333966584358;5212.791488340462;5221.791128354861;5229.11105112128;5232.502912223371;12439.502419903192;12650.936745316214;13689;15848.44530441429;15860.127692976881;2320.709911261092;2321.338418550705;2335.334429687548;2335.9182428615086;2415.9637605435933;9347.018563050919;9500.049995000514;9527.952360238223;9529.285303602188;9795.706128816075;1900.4773687235981;1938.806119388064;1949.2495389275089;1963.3128405058258;1966.3314473079079;59802.70098649523;59995;60550;61753.691231544006;62209.37790622081;9632.90367096334;9715.805683886283;9720.222382209406;9727.9027209727;9787.951060244653;12319.63041108768;12392.938035309766;12394;12403.441845116991;12406.379681015913;7351.8162045949275;7416.406687465098;7597.962010189914;7655.157932627407;7673.654685539164;7497.287757663123;7512.39900807934;7543.886841697379;7568.659410326549;7585.544867307979;4150.937735933963;4199.4120823084795;4212.304969679968;4219.198352313051;4230.15396920611;1613.6127329440849;1683.511781583327;1687.3503701074928;1801.4955812372557;1833.275856036858;1011.8785745710562;1079.2930630437077;1093.3439936038471;1128.8814674459227;1141.3437273567731;13470.528531501546;16102.114383708775;16304.429344972868;16339.58874026747;16605.169741512876;997.835357166074;1004.4726518577805;1038.8909164537765;1041.047441590951;1062.4846949229618;9581.185599224005;9958.850617240747;9979.351342162736;10016.54925528353;10037.146842518321;571671;587930;636029;640336;649852;81636.59181704173;82005.17994819976;83238.16761832425;83818.58090709508;85277.99999999876;1103.7957977774138;1108.5953626926114;1142.4687520303098;1162.5640384855678;1162.68607475981;15086.698266034837;15483.922580387027;15526.689466210839;15567.610809729847;15746.842531574537;33654.66345336515;33941.981740547824;34321.48517772186;35678.10804729902;36543.08642283964;40328.79835600803;41304.79347603243;41735.58264417317;42712.57287427086;43446.565534345515;9316.627334906529;9730.805383892424;9751.073648003428;9759.707208783748;9760.951195243979;17514.649707006043;18144.546386340447;18183.909080454512;18269.54326141804;18435;15343.386264549294;15488.457903973307;15646.530604081896;15684.68630627404;15714.057156570643;14947.177905215318;15096.16971066602;15134.730538922156;15449;15672;12311.815322769986;14733.336999834819;15285.6178595536;15331.846681533489;15352.23238838076;9602.135807777333;9663.661771837951;9707;9778.853317200106;9803.509824508888;10063.245256605638;10123.392596443942;14730;14755.483558075415;14938.029028113138 10:47:36 INFO - 2016-02-25 10:47:36,458 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1538.1463287875229;1589.237166160243;1598.2807736518569;1766.3376233912284;1869.8410635096016;7284.526505777121;7705.614719264036;7713.3829293656445;7726.690932362712;7732.381409487241;7462.328390444859;7612.61936903154;7626.961865190667;7640.808979775508;7643;2230.453538882976;2260.050778672953;2297.5060362022164;2298.7011688480475;2300.7814257645496;2199.560087982402;2230.9219177328796;2231.0852550454315;2232.0179121186707;2232.7097477327925;1961.7743959444633;1984.8412127029803;2150.3441450357636;2207.569523942832;2251.470904337479;2224.0547767198955;2232.5088480534255;2238.7425446073667;2244.9999999999836;2250.066222517645;2224.1214720185435;2292.5071109711325;2292.7707229277107;2293.0827668932393;2295.070496448924;6861.142357205349;7047.788566342967;7445.553266804009;7520.172780994087;7548.622568871535;4678.812847486097;4713.9764301178275;4718.3158442025715;4726.480087190406;4750.524947505256;2934.706529347048;2956.127942257039;2976.4642364374404;2979.9851000744857;3021.864016119258;1020.515255253756;1025.3335332034162;1045.6706137566641;1046.1108058150587;1055.1822337688277;10052.195824334109;10128.696139115838;10200.489975501196;10204.183665306758;10284.999999999925;4124.443200167999;4169.666426685858;4188.560201178893;4194.622483976395;4197.643200328006;8925.955370223108;8951.239144672645;8964.282857371394;8977.640894364153;9030;4547.408836851188;4564.0871825635;4568.1548913451115;4569.200389931749;4569.223232050559;4070.1452694933805;4077.368007958771;4078.204750073707;4088.366303223005;4090.734102283342;3480.773749706261;3533.4876442916147;3555.839987200582;3560.038789526813;3564.215872508044;1105.4472763618191;1118.222835129577;1120.3501968858043;1133.1388145009853;1133.8015847226702;1121.876593574706;1141.4806263150413;1149.6321177223208;1304.6738315421144;1368.3773882883265;7314.305141011595;7331.083614548182;7381.926180738231;7407.222241664547;7506.699732010768;1166.2128063557066;1187.8040123379546;1196.970075748096;1200.880978244612;1203.7291609387817;5659.547236221092;5981.910271345933;6022.909656355158;6061.818145455643;6070.969645151746;8237.546934918517;8907.51008694528;9040.63837446508;9048.276137908952;9083.727488175366;8574.785630359167;8873.73378798637;8983.101689830964;9007.23438507721;9080.81838363229;7350.338469537767;7448.1434635016585;7458.179600243969;7477.775666730006;7479.401647868158;7285.599292038989;7346.5592064476305;7382.298681625237;7429.182789893215;7435.925640743523;8124.187581242065;8209.138040505899;8260.669573217005;8275.586220689062;8290.212429819157;8326.333893288402;8326.542040187847;8405.07544170153;8519.829603408021;8611.13888611134;7009.579425234502;7010.474214433836;7039.190493093155;7039.401650859734;7077.186123595852;7483.289087536676;7557.168711441627;7587.58268295249;7594.202608726005;7604.619769011638;7068;7098.3256590623805;7124.928750712426;7141.250168732416;7162.928370716226;7035.226125126334;7091.787246382617;7092.822679433056;7179.246179151114;7180;7168.713251469882;7212.783616491513;7233.204347521873;7234.240404757425;7242.637868106468;6624.271330153583;6687.063811066552;6691.130153080068;6695.397414232742;6700.43046341047;6634.402903738686;6664.666766661744;6670.633115178712;6670.666466676744;6679.532432729857 10:47:36 INFO - 2016-02-25 10:47:36,459 DEBUG : BROWSER_OUTPUT: |4;prototype.html;343.3716299172515;346.92887957968617;349.36241359518885;351.63781305255594;352.31242349147584;5904.0553511438175;5962;6063.514918806496;6068.544859135566;6096.177016102824;5794.739236734346;5947.6134051286635;5971.761129554823;5977.222961015062;5994.550408719341;4680.391549098621;4691.7184968901975;4695.483496815347;4697.013627138305;4710.269908164223;4499.41507604011;4561.863144105682;4563.657725670571;4579.267317229244;4701.788419521113;4627.259638457847;4643.419572553431;4645.117427688745;4665.370175026362;4671.88320291992;4632.675712700112;4636.907261854744;4638.768061596924;4649.535046495357;4661.6969896956925;4106;4113.9635880239175;4117.649999750025;4126.298529250034;4137.8758637240935;5541.889162216773;5703.714814259312;5708.20085188074;5716.514096301819;5731.369549349568;5412.269343638598;5413.729313534347;5417.729113544307;5426.430224826416;5430.70131142787;6069.332373438918;6333.651649159295;6361.872762544769;6370.235571731376;6371;4423.270160423526;4455.4207952966235;4541.091781643651;4548.56788605082;4564.931526027112;6545.705443255065;6602.537822352389;6602.93397066028;6603.966980165068;6614.9416093424825;4677.976610116928;4678.345031695565;4687.929681054787;4688.343631891538;4692.343071969927;4682.458015724742;4714.339992401066;4752.5485078917445;4753.120672675566;4759.095771803346;5399.325084364455;5404.0272750905215;5406.6215364924865;5408.432114627986;5412.917416516712;6407.487401008001;6408.743650253938;6411.4229719325485;6412.775552855721;6412.807615771534;1642.5729310379388;1644.8272931342276;1646.0617448054495;1647.5057482755126;1648.250046228963;1078.82199437092;1081.8052750504985;1096.4243772019754;1156.9247998880046;1184.0882520459197;6252.030935205051;6258.9374106259265;6271.43557079865;6276.215473065867;6277.780277690259;6571.441427478718;6607.702653380609;6612.437942774822;6614.305497922745;6619;6166.568340216231;6240.937590624127;6244.718987645476;6246.812595622138;6251.749930002749;3566.7859928404378;3604.675579197885;3605.116746397097;3609.061643972586;3611.313850368474;6899.241083480813;6901.62041087735;6907.378335949688;6913.654317284116;6914.412274956685;6655.035019922084;6861.279565645636;6868.141482314711;6872.828179295558;6874.690638921161;6834.794956151323;6904.93095069063;6905.309469053054;6906.309369063053;6917.204521479994;6398.840028999312;6561.3438656134;6579.80260592183;6686.264510903894;6709.832254193489;6552.148220731465;6575.309592492719;6595.406413422815;6601.471882249312;6608.900866486911;6827.180738311435;6862.725490980305;6870.3473170048765;6873.347032031949;6880.346367095118;4448.755318457386;4563.566461186182;4572.314152876962;4616.845788552861;4662.953370466387;6624.973129164987;6628.5691430056895;6629.4696424284975;6634.071230027704;6640.435562977008;6471.935280647133;6481.773137940149;6483.481321494363;6487.870242595216;6489.091527186292;6317.273513546001;6460.741570337133;6460.773872914426;6462.095306656977;6474.093626892333;6340.524460665376;6340.587861788969;6348.333424990309;6350.7459701611415;6403.743850245938;6543.116679248431;6546.901796472961;6554.639494827639;6556.573822701509;6557.540972132096;6261.373862613885;6391.648459334595;6392.105105285357;6395.136656551306;6395.584287021329 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: |5;yui.html;810.6878851642116;825.0511911301999;825.0635528674954;835.4026870787386;877.1973644115634;14612.342444589993;14972.401103955855;15022.774658380133;15025.248737563119;15049.774253386207;13521;13604.455821767142;13641.386137623806;13646.863531364683;13667.384967676453;698.34006863514;711.537500624593;851.6550796927239;857.3698331726176;883.0507204754882;861.1689719420758;862.7541150772031;863.6329559937033;868.1969178509853;870.0864092702668;743.1305372713938;920.4293338130368;925.7685578605349;926.4163576946514;932.7901222225013;749.4603885202646;751.3876190904396;751.5378042503876;914.6935776514875;940.3323640215456;666.6067020457953;667;673.939345458911;673.9865202695919;674.305465370669;14141;14156.150630962175;14220.360083796253;14325.498607548685;14365.353559089866;13265.602031939057;13403.72136736757;13588.660283492893;13672.384742686603;13976.161430314112;11044.116470682326;11160.330380177134;11203.271787333797;11354;11453.427328633536;3776.7356285060055;3874.341361968444;3887.5723670396233;3905.218956208741;3923.8430462781453;48874.51125488771;50722;50764;51188;51339.743301283255;11073.335599864035;11083.556657733761;11114.666560003212;11232.606858759904;11613.88386116145;20651.380458586267;20686.379408617766;20762.896185518977;20828.58342833135;20932.581348373253;8022.879656805153;8193;8388.538630375266;8578.356623253156;8595.484270943763;4591.586757191869;4631.768411579407;4649.837255696035;4661.696989695659;4679.672422930362;3090.98454507726;3093.8453077346494;3093.8917137900066;3107.766917481198;3108.2695566541843;564.6781334639298;578.4851482180862;581.1921429213397;583.4165834165834;593.8099808061465;559.6278474814203;629.7418058595954;703.4477934821141;704.4751660013322;751.1361933776201;8530.274926631306;8548.487090774574;8780.648774048967;8864.335174861912;8932.868683035927;617.6448542088317;668.1314291421133;669.0900375489331;690.0890824112124;724.5109551053018;4242.412227662904;4402.84590039347;4442.777861106932;4450.421445212101;4540.523245059288;56331;57052;57153.428465715646;57225.14162287569;57322.140167897516;52701.736491318065;52722.814054386996;53313.466865331626;53592.46407535953;53707.462925370244;47599;47950.760246198544;48561;48681.26978095331;48750.512494874594;8779.385443018928;8932.553372331487;8962.193402593537;9024.063507814439;9110.407823491718;34921.47617785786;35162.296754064264;35873.28253434969;36044.63955360413;36646.63353366539;47666.28500572564;48751.756241219984;48912;49256.507434925195;49645.75177124091;11271.267367621078;11568.537258509901;11665.88334116648;11668.183227174017;12280.81578776337;13693.520726774515;13752.724945500833;13807.585772426843;13847.238401887993;13928.373223204777;12342.629721108382;12559.372031398576;12621.936890315857;12645.348111535412;13237.27195004284;12250.509979601073;12299.569515066929;12496.500139994298;12674.112812103065;12830.615081547568;10799;10820;11203;11217.55129794799;11416.143789215675;9666.709998700051;9865.408075515492;9935;10162.136218421518;10228.284020118817;10281.280310378203;10594.20543459259;10979.17656175806;11157;11258 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,818 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: __startTimestamp1456426056451__endTimestamp 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:47:36 INFO - 2016-02-25 10:47:36,461 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5450.50 Median:5687.09 stddev:588.56 (10.3%) stddev-sans-first:564.98 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: Values: 3270.7 3336.3 3354.7 3357.1 3379.5 5442.2 5450.5 5454.8 5461.2 5489.0 5454.1 5471.3 5479.3 5495.2 5495.8 4888.5 4975.5 5013.8 5040.5 5071.9 4920.3 4977.0 4981.9 4982.3 4986.1 4911.5 4954.7 5006.9 5010.4 5014.7 5061.1 5104.4 5107.3 5111.7 5137.5 4976.6 5022.9 5068.1 5069.9 5073.7 5153.0 5176.0 5242.3 5302.7 5304.8 5783.7 5806.8 5833.4 5836.1 5842.0 5700.5 5887.5 5892.6 5898.3 5902.1 5389.7 5409.0 5473.4 5481.5 5505.9 5955.3 5956.3 5958.6 5959.9 5962.0 5547.4 5693.7 5791.5 5793.3 5799.5 5737.6 5840.6 5849.1 5874.1 5882.2 5850.8 5883.5 5888.0 5889.7 5890.2 5756.5 5882.5 5892.6 5899.8 5900.6 4470.1 4511.6 4512.1 4514.6 4517.2 3990.6 4043.5 4075.6 4079.4 4096.4 5620.8 5690.1 5697.9 5701.5 5702.2 5892.9 5917.5 5947.9 5950.1 5952.2 5438.5 5502.6 5570.1 5593.2 5625.7 5074.2 5087.8 5093.9 5097.3 5139.4 5964.5 6026.0 6027.6 6029.5 6033.8 5754.5 5824.0 5833.8 5839.1 5840.3 6025.8 6029.3 6041.8 6042.4 6056.5 5648.7 5759.6 5794.3 5797.0 5798.6 5840.1 5957.3 5966.2 5966.5 5980.8 5718.4 5723.4 5724.3 5724.9 5737.7 5579.6 5621.6 5725.0 5732.6 5742.6 5565.7 5600.3 5635.7 5662.7 5678.6 5779.9 5787.5 5801.8 5804.1 5809.2 5504.5 5597.7 5687.1 5691.3 5694.3 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:53235.55 Median:35082.39 stddev:93026.91 (265.2%) stddev-sans-first:93203.86 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: Values: 576.2 606.1 616.6 621.8 623.0 31626.5 40271.2 40342.0 40346.0 40423.0 45284.3 45654.0 45689.1 45759.3 45919.0 8185.6 8319.4 9073.5 9257.2 10451.5 10626.7 10792.7 10801.2 10810.8 10816.8 10705.5 10745.3 10828.7 10831.9 10840.0 10739.8 10831.2 10843.1 10859.7 10860.6 10570.2 10689.9 10695.6 10712.9 10722.1 41711.6 41896.4 42022.6 42157.6 42205.6 3318.8 3383.9 3578.1 3718.4 4111.6 29959.3 30431.4 30463.8 30495.0 31100.0 3632.5 3835.6 3850.3 3856.0 3870.1 300473.0 302041.5 302447.0 303719.0 304061.0 59034.0 60089.7 62765.7 62820.1 68158.0 63920.0 64475.4 64537.0 64574.4 64784.4 15640.1 15717.2 15806.4 15817.7 15821.4 12178.3 12377.6 12384.1 12386.7 12387.6 20988.9 21506.6 21609.9 21646.5 21658.5 8480.4 8560.4 8611.4 8673.6 8717.0 1059.5 1129.1 1140.7 1144.8 1149.0 29147.3 29352.4 29986.3 30024.1 33595.2 1075.9 1089.5 1100.6 1166.7 1175.7 13615.9 14753.3 14842.1 16553.6 17180.9 427990.0 431237.0 432798.0 434023.0 434584.0 56489.7 57424.4 57457.0 57531.0 57585.7 1070.5 1370.0 1459.0 1471.8 1565.4 29898.4 30160.2 34238.8 34983.5 35181.3 53995.5 54719.7 54728.0 54743.0 54776.2 311648.0 312902.0 314186.0 314205.0 314860.0 45267.3 46958.0 47091.0 47581.5 48307.8 45814.0 45992.8 46721.0 47069.3 47090.1 38486.4 38515.2 38986.8 39243.6 40143.0 38657.6 38975.4 39208.4 39293.4 39507.2 37682.2 38260.8 38331.8 38711.4 38831.6 33999.7 34540.8 34962.5 35211.0 35228.3 26740.7 26785.5 26822.0 27122.3 27254.6 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30853.59 Median:362.07 stddev:100913.50 (27871.0%) stddev-sans-first:101170.61 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: Values: 360.5 363.6 364.3 369.4 371.2 14637.7 15078.5 17091.8 17632.3 18121.2 16603.4 16708.2 16934.6 17591.2 17619.0 5505.6 5659.7 5661.9 5663.0 5666.1 4215.2 4296.0 5564.4 5845.6 6141.4 6026.2 6042.7 6064.3 6088.8 6093.8 5294.0 5411.0 5419.5 5432.7 5434.2 5123.3 5212.8 5221.8 5229.1 5232.5 12439.5 12650.9 13689.0 15848.4 15860.1 2320.7 2321.3 2335.3 2335.9 2416.0 9347.0 9500.0 9528.0 9529.3 9795.7 1900.5 1938.8 1949.2 1963.3 1966.3 59802.7 59995.0 60550.0 61753.7 62209.4 9632.9 9715.8 9720.2 9727.9 9788.0 12319.6 12392.9 12394.0 12403.4 12406.4 7351.8 7416.4 7598.0 7655.2 7673.7 7497.3 7512.4 7543.9 7568.7 7585.5 4150.9 4199.4 4212.3 4219.2 4230.2 1613.6 1683.5 1687.4 1801.5 1833.3 1011.9 1079.3 1093.3 1128.9 1141.3 13470.5 16102.1 16304.4 16339.6 16605.2 997.8 1004.5 1038.9 1041.0 1062.5 9581.2 9958.9 9979.4 10016.5 10037.1 571671.0 587930.0 636029.0 640336.0 649852.0 81636.6 82005.2 83238.2 83818.6 85278.0 1103.8 1108.6 1142.5 1162.6 1162.7 15086.7 15483.9 15526.7 15567.6 15746.8 33654.7 33942.0 34321.5 35678.1 36543.1 40328.8 41304.8 41735.6 42712.6 43446.6 9316.6 9730.8 9751.1 9759.7 9761.0 17514.6 18144.5 18183.9 18269.5 18435.0 15343.4 15488.5 15646.5 15684.7 15714.1 14947.2 15096.2 15134.7 15449.0 15672.0 12311.8 14733.3 15285.6 15331.8 15352.2 9602.1 9663.7 9707.0 9778.9 9803.5 10063.2 10123.4 14730.0 14755.5 14938.0 10:47:36 INFO - 2016-02-25 10:47:36,462 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,463 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5418.95 Median:6629.34 stddev:2763.86 (41.7%) stddev-sans-first:2756.22 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: Values: 1538.1 1589.2 1598.3 1766.3 1869.8 7284.5 7705.6 7713.4 7726.7 7732.4 7462.3 7612.6 7627.0 7640.8 7643.0 2230.5 2260.1 2297.5 2298.7 2300.8 2199.6 2230.9 2231.1 2232.0 2232.7 1961.8 1984.8 2150.3 2207.6 2251.5 2224.1 2232.5 2238.7 2245.0 2250.1 2224.1 2292.5 2292.8 2293.1 2295.1 6861.1 7047.8 7445.6 7520.2 7548.6 4678.8 4714.0 4718.3 4726.5 4750.5 2934.7 2956.1 2976.5 2980.0 3021.9 1020.5 1025.3 1045.7 1046.1 1055.2 10052.2 10128.7 10200.5 10204.2 10285.0 4124.4 4169.7 4188.6 4194.6 4197.6 8926.0 8951.2 8964.3 8977.6 9030.0 4547.4 4564.1 4568.2 4569.2 4569.2 4070.1 4077.4 4078.2 4088.4 4090.7 3480.8 3533.5 3555.8 3560.0 3564.2 1105.4 1118.2 1120.4 1133.1 1133.8 1121.9 1141.5 1149.6 1304.7 1368.4 7314.3 7331.1 7381.9 7407.2 7506.7 1166.2 1187.8 1197.0 1200.9 1203.7 5659.5 5981.9 6022.9 6061.8 6071.0 8237.5 8907.5 9040.6 9048.3 9083.7 8574.8 8873.7 8983.1 9007.2 9080.8 7350.3 7448.1 7458.2 7477.8 7479.4 7285.6 7346.6 7382.3 7429.2 7435.9 8124.2 8209.1 8260.7 8275.6 8290.2 8326.3 8326.5 8405.1 8519.8 8611.1 7009.6 7010.5 7039.2 7039.4 7077.2 7483.3 7557.2 7587.6 7594.2 7604.6 7068.0 7098.3 7124.9 7141.3 7162.9 7035.2 7091.8 7092.8 7179.2 7180.0 7168.7 7212.8 7233.2 7234.2 7242.6 6624.3 6687.1 6691.1 6695.4 6700.4 6634.4 6664.7 6670.6 6670.7 6679.5 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5396.16 Median:6131.37 stddev:1611.43 (26.3%) stddev-sans-first:1570.68 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: Values: 343.4 346.9 349.4 351.6 352.3 5904.1 5962.0 6063.5 6068.5 6096.2 5794.7 5947.6 5971.8 5977.2 5994.6 4680.4 4691.7 4695.5 4697.0 4710.3 4499.4 4561.9 4563.7 4579.3 4701.8 4627.3 4643.4 4645.1 4665.4 4671.9 4632.7 4636.9 4638.8 4649.5 4661.7 4106.0 4114.0 4117.6 4126.3 4137.9 5541.9 5703.7 5708.2 5716.5 5731.4 5412.3 5413.7 5417.7 5426.4 5430.7 6069.3 6333.7 6361.9 6370.2 6371.0 4423.3 4455.4 4541.1 4548.6 4564.9 6545.7 6602.5 6602.9 6604.0 6614.9 4678.0 4678.3 4687.9 4688.3 4692.3 4682.5 4714.3 4752.5 4753.1 4759.1 5399.3 5404.0 5406.6 5408.4 5412.9 6407.5 6408.7 6411.4 6412.8 6412.8 1642.6 1644.8 1646.1 1647.5 1648.3 1078.8 1081.8 1096.4 1156.9 1184.1 6252.0 6258.9 6271.4 6276.2 6277.8 6571.4 6607.7 6612.4 6614.3 6619.0 6166.6 6240.9 6244.7 6246.8 6251.7 3566.8 3604.7 3605.1 3609.1 3611.3 6899.2 6901.6 6907.4 6913.7 6914.4 6655.0 6861.3 6868.1 6872.8 6874.7 6834.8 6904.9 6905.3 6906.3 6917.2 6398.8 6561.3 6579.8 6686.3 6709.8 6552.1 6575.3 6595.4 6601.5 6608.9 6827.2 6862.7 6870.3 6873.3 6880.3 4448.8 4563.6 4572.3 4616.8 4663.0 6625.0 6628.6 6629.5 6634.1 6640.4 6471.9 6481.8 6483.5 6487.9 6489.1 6317.3 6460.7 6460.8 6462.1 6474.1 6340.5 6340.6 6348.3 6350.7 6403.7 6543.1 6546.9 6554.6 6556.6 6557.5 6261.4 6391.6 6392.1 6395.1 6395.6 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14577.57 Median:4670.68 stddev:16503.62 (353.3%) stddev-sans-first:16517.53 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: Values: 810.7 825.1 825.1 835.4 877.2 14612.3 14972.4 15022.8 15025.2 15049.8 13521.0 13604.5 13641.4 13646.9 13667.4 698.3 711.5 851.7 857.4 883.1 861.2 862.8 863.6 868.2 870.1 743.1 920.4 925.8 926.4 932.8 749.5 751.4 751.5 914.7 940.3 666.6 667.0 673.9 674.0 674.3 14141.0 14156.2 14220.4 14325.5 14365.4 13265.6 13403.7 13588.7 13672.4 13976.2 11044.1 11160.3 11203.3 11354.0 11453.4 3776.7 3874.3 3887.6 3905.2 3923.8 48874.5 50722.0 50764.0 51188.0 51339.7 11073.3 11083.6 11114.7 11232.6 11613.9 20651.4 20686.4 20762.9 20828.6 20932.6 8022.9 8193.0 8388.5 8578.4 8595.5 4591.6 4631.8 4649.8 4661.7 4679.7 3091.0 3093.8 3093.9 3107.8 3108.3 564.7 578.5 581.2 583.4 593.8 559.6 629.7 703.4 704.5 751.1 8530.3 8548.5 8780.6 8864.3 8932.9 617.6 668.1 669.1 690.1 724.5 4242.4 4402.8 4442.8 4450.4 4540.5 56331.0 57052.0 57153.4 57225.1 57322.1 52701.7 52722.8 53313.5 53592.5 53707.5 47599.0 47950.8 48561.0 48681.3 48750.5 8779.4 8932.6 8962.2 9024.1 9110.4 34921.5 35162.3 35873.3 36044.6 36646.6 47666.3 48751.8 48912.0 49256.5 49645.8 11271.3 11568.5 11665.9 11668.2 12280.8 13693.5 13752.7 13807.6 13847.2 13928.4 12342.6 12559.4 12621.9 12645.3 13237.3 12250.5 12299.6 12496.5 12674.1 12830.6 10799.0 10820.0 11203.0 11217.6 11416.1 9666.7 9865.4 9935.0 10162.1 10228.3 10281.3 10594.2 10979.2 11157.0 11258.0 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:47:36 INFO - 2016-02-25 10:47:36,464 DEBUG : BROWSER_OUTPUT: 10:47:37 INFO - 2016-02-25 10:47:37,045 INFO : Browser exited with error code: 0 10:47:37 INFO - 2016-02-25 10:47:37,063 INFO : Completed test dromaeo_css (00:18:30) 10:47:37 INFO - 2016-02-25 10:47:37,063 INFO : Starting test kraken 10:47:37 INFO - 2016-02-25 10:47:37,064 DEBUG : operating with platform_type : linux_ 10:47:37 INFO - 2016-02-25 10:47:37,064 INFO : Initialising browser for kraken test... 10:47:37 INFO - 2016-02-25 10:47:37,070 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpG1br3B/profile http://localhost:49143/getInfo.html 10:47:40 INFO - 2016-02-25 10:47:40,628 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:47:40 INFO - 2016-02-25 10:47:40,628 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:47:40 INFO - 2016-02-25 10:47:40,628 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:47:40 INFO - 2016-02-25 10:47:40,628 DEBUG : BROWSER_OUTPUT: __metrics 10:47:40 INFO - 2016-02-25 10:47:40,805 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:47:41 INFO - 2016-02-25 10:47:41,873 INFO : Browser initialized. 10:47:41 INFO - 2016-02-25 10:47:41,874 INFO : Running cycle 1/1 for kraken test... 10:47:41 INFO - 2016-02-25 10:47:41,874 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpG1br3B/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:47:53 INFO - 2016-02-25 10:47:53,525 DEBUG : BROWSER_OUTPUT: RSS: Main: 134021120 10:47:53 INFO - 2016-02-25 10:47:53,525 DEBUG : BROWSER_OUTPUT: 10:47:55 INFO - 2016-02-25 10:47:55,944 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49143/tests/kraken/driver.html?testName=audio-beat-detection) 10:47:56 INFO - 2016-02-25 10:47:56,527 DEBUG : BROWSER_OUTPUT: RSS: Main: 165019648 10:47:56 INFO - 2016-02-25 10:47:56,527 DEBUG : BROWSER_OUTPUT: 10:47:59 INFO - 2016-02-25 10:47:59,071 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49143/tests/kraken/driver.html?testName=audio-dft) 10:47:59 INFO - 2016-02-25 10:47:59,637 DEBUG : BROWSER_OUTPUT: RSS: Main: 160772096 10:47:59 INFO - 2016-02-25 10:47:59,637 DEBUG : BROWSER_OUTPUT: 10:48:02 INFO - 2016-02-25 10:48:02,424 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49143/tests/kraken/driver.html?testName=audio-fft) 10:48:02 INFO - 2016-02-25 10:48:02,992 DEBUG : BROWSER_OUTPUT: RSS: Main: 160579584 10:48:02 INFO - 2016-02-25 10:48:02,992 DEBUG : BROWSER_OUTPUT: 10:48:04 INFO - 2016-02-25 10:48:04,973 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49143/tests/kraken/driver.html?testName=audio-oscillator) 10:48:05 INFO - 2016-02-25 10:48:05,539 DEBUG : BROWSER_OUTPUT: RSS: Main: 159170560 10:48:05 INFO - 2016-02-25 10:48:05,539 DEBUG : BROWSER_OUTPUT: 10:48:07 INFO - 2016-02-25 10:48:07,698 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49143/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:48:08 INFO - 2016-02-25 10:48:08,260 DEBUG : BROWSER_OUTPUT: RSS: Main: 158617600 10:48:08 INFO - 2016-02-25 10:48:08,260 DEBUG : BROWSER_OUTPUT: 10:48:11 INFO - 2016-02-25 10:48:11,118 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49143/tests/kraken/driver.html?testName=imaging-darkroom) 10:48:11 INFO - 2016-02-25 10:48:11,685 DEBUG : BROWSER_OUTPUT: RSS: Main: 158830592 10:48:11 INFO - 2016-02-25 10:48:11,685 DEBUG : BROWSER_OUTPUT: 10:48:15 INFO - 2016-02-25 10:48:15,008 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49143/tests/kraken/driver.html?testName=imaging-desaturate) 10:48:15 INFO - 2016-02-25 10:48:15,575 DEBUG : BROWSER_OUTPUT: RSS: Main: 158851072 10:48:15 INFO - 2016-02-25 10:48:15,575 DEBUG : BROWSER_OUTPUT: 10:48:18 INFO - 2016-02-25 10:48:18,348 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49143/tests/kraken/driver.html?testName=json-parse-financial) 10:48:18 INFO - 2016-02-25 10:48:18,914 DEBUG : BROWSER_OUTPUT: RSS: Main: 157954048 10:48:18 INFO - 2016-02-25 10:48:18,915 DEBUG : BROWSER_OUTPUT: 10:48:20 INFO - 2016-02-25 10:48:20,464 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49143/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:48:21 INFO - 2016-02-25 10:48:21,026 DEBUG : BROWSER_OUTPUT: RSS: Main: 164061184 10:48:21 INFO - 2016-02-25 10:48:21,026 DEBUG : BROWSER_OUTPUT: 10:48:22 INFO - 2016-02-25 10:48:22,761 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49143/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:48:23 INFO - 2016-02-25 10:48:23,349 DEBUG : BROWSER_OUTPUT: RSS: Main: 161157120 10:48:23 INFO - 2016-02-25 10:48:23,349 DEBUG : BROWSER_OUTPUT: 10:48:25 INFO - 2016-02-25 10:48:25,350 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49143/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:48:25 INFO - 2016-02-25 10:48:25,919 DEBUG : BROWSER_OUTPUT: RSS: Main: 169889792 10:48:25 INFO - 2016-02-25 10:48:25,919 DEBUG : BROWSER_OUTPUT: 10:48:28 INFO - 2016-02-25 10:48:28,254 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49143/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:48:28 INFO - 2016-02-25 10:48:28,835 DEBUG : BROWSER_OUTPUT: RSS: Main: 174800896 10:48:28 INFO - 2016-02-25 10:48:28,835 DEBUG : BROWSER_OUTPUT: 10:48:31 INFO - 2016-02-25 10:48:31,310 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49143/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:48:31 INFO - 2016-02-25 10:48:31,880 DEBUG : BROWSER_OUTPUT: RSS: Main: 171343872 10:48:31 INFO - 2016-02-25 10:48:31,880 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,517 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49143/tests/kraken/driver.html?testName=ai-astar) 10:48:33 INFO - 2016-02-25 10:48:33,768 DEBUG : BROWSER_OUTPUT: RSS: Main: 229842944 10:48:33 INFO - 2016-02-25 10:48:33,769 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,770 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:48:33 INFO - 2016-02-25 10:48:33,770 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:48:33 INFO - 2016-02-25 10:48:33,770 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:48:33 INFO - 2016-02-25 10:48:33,771 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:48:33 INFO - 2016-02-25 10:48:33,771 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;104;109;110;110;100;109;109;109 10:48:33 INFO - 2016-02-25 10:48:33,772 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;158;121;151;160;121;150;156;121;159;120 10:48:33 INFO - 2016-02-25 10:48:33,772 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;158;160;161;158;160;162;159;158 10:48:33 INFO - 2016-02-25 10:48:33,773 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;71;160;71;71;71;70;163;72 10:48:33 INFO - 2016-02-25 10:48:33,773 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;146;92;92;92;134;92;93;132 10:48:33 INFO - 2016-02-25 10:48:33,773 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;109;111;110;109;110;109;109 10:48:33 INFO - 2016-02-25 10:48:33,773 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;146;146;146;146;147;145;145 10:48:33 INFO - 2016-02-25 10:48:33,773 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;95;96;96;95;96;96;96;96;96 10:48:33 INFO - 2016-02-25 10:48:33,774 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;57;57;57;57;57;59;59;55;56 10:48:33 INFO - 2016-02-25 10:48:33,774 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;64;64;64;64;65;65;65;64;65 10:48:33 INFO - 2016-02-25 10:48:33,774 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;77;83;86;77;77;85;77;76;77;77 10:48:33 INFO - 2016-02-25 10:48:33,774 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;110;111;111;111;109;111;117;123;111;119 10:48:33 INFO - 2016-02-25 10:48:33,774 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;130;140;139;139;141;138;139;151;143;139 10:48:33 INFO - 2016-02-25 10:48:33,774 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;62;62;64;63;61;63;66;63;63 10:48:33 INFO - 2016-02-25 10:48:33,775 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:48:33 INFO - 2016-02-25 10:48:33,775 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:48:33 INFO - 2016-02-25 10:48:33,775 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,881 10:48:33 INFO - 2016-02-25 10:48:33,775 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:48:33 INFO - 2016-02-25 10:48:33,776 DEBUG : BROWSER_OUTPUT: __startTimestamp1456426113770__endTimestamp 10:48:33 INFO - 2016-02-25 10:48:33,776 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,777 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:48:33 INFO - 2016-02-25 10:48:33,777 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:48:33 INFO - 2016-02-25 10:48:33,777 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,778 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.00 Median:109.00 stddev:3.37 (3.1%) stddev-sans-first:3.55 10:48:33 INFO - 2016-02-25 10:48:33,778 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 104.0 109.0 110.0 110.0 100.0 109.0 109.0 109.0 10:48:33 INFO - 2016-02-25 10:48:33,778 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,778 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.70 Median:153.50 stddev:18.31 (11.9%) stddev-sans-first:18.44 10:48:33 INFO - 2016-02-25 10:48:33,779 DEBUG : BROWSER_OUTPUT: Values: 158.0 121.0 151.0 160.0 121.0 150.0 156.0 121.0 159.0 120.0 10:48:33 INFO - 2016-02-25 10:48:33,779 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,779 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:1.42 (0.9%) stddev-sans-first:1.50 10:48:33 INFO - 2016-02-25 10:48:33,779 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 158.0 160.0 161.0 158.0 160.0 162.0 159.0 158.0 10:48:33 INFO - 2016-02-25 10:48:33,780 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,780 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.20 Median:71.00 stddev:38.12 (53.7%) stddev-sans-first:39.92 10:48:33 INFO - 2016-02-25 10:48:33,780 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 71.0 160.0 71.0 71.0 71.0 70.0 163.0 72.0 10:48:33 INFO - 2016-02-25 10:48:33,780 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.90 Median:92.00 stddev:21.99 (23.9%) stddev-sans-first:22.82 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 146.0 92.0 92.0 92.0 134.0 92.0 93.0 132.0 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 109.0 111.0 110.0 109.0 110.0 109.0 109.0 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 146.0 146.0 146.0 146.0 147.0 145.0 145.0 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,782 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: Values: 96.0 95.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 96.0 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:1.25 (2.2%) stddev-sans-first:1.27 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: Values: 56.0 57.0 57.0 57.0 57.0 57.0 59.0 59.0 55.0 56.0 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.50 stddev:0.67 (1.0%) stddev-sans-first:0.53 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: Values: 63.0 64.0 64.0 64.0 64.0 65.0 65.0 65.0 64.0 65.0 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:77.00 stddev:3.85 (5.0%) stddev-sans-first:4.00 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: Values: 77.0 83.0 86.0 77.0 77.0 85.0 77.0 76.0 77.0 77.0 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:113.30 Median:111.00 stddev:4.67 (4.2%) stddev-sans-first:4.80 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 111.0 111.0 109.0 111.0 117.0 123.0 111.0 119.0 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.90 Median:139.50 stddev:5.15 (3.7%) stddev-sans-first:4.03 10:48:33 INFO - 2016-02-25 10:48:33,783 DEBUG : BROWSER_OUTPUT: Values: 130.0 140.0 139.0 139.0 141.0 138.0 139.0 151.0 143.0 139.0 10:48:33 INFO - 2016-02-25 10:48:33,784 DEBUG : BROWSER_OUTPUT: 10:48:33 INFO - 2016-02-25 10:48:33,784 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.00 Median:63.00 stddev:1.33 (2.1%) stddev-sans-first:1.41 10:48:33 INFO - 2016-02-25 10:48:33,784 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 62.0 64.0 63.0 61.0 63.0 66.0 63.0 63.0 10:48:33 INFO - 2016-02-25 10:48:33,784 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:48:33 INFO - 2016-02-25 10:48:33,784 DEBUG : BROWSER_OUTPUT: 10:48:34 INFO - 2016-02-25 10:48:34,453 INFO : Browser exited with error code: 0 10:48:34 INFO - 2016-02-25 10:48:34,513 INFO : Completed test kraken (00:00:57) 10:48:34 INFO - 2016-02-25 10:48:34,513 INFO : Starting test v8_7 10:48:34 INFO - 2016-02-25 10:48:34,513 DEBUG : operating with platform_type : linux_ 10:48:34 INFO - 2016-02-25 10:48:34,513 INFO : Initialising browser for v8_7 test... 10:48:34 INFO - 2016-02-25 10:48:34,519 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbieZqL/profile http://localhost:49143/getInfo.html 10:48:38 INFO - 2016-02-25 10:48:38,215 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:48:38 INFO - 2016-02-25 10:48:38,215 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:48:38 INFO - 2016-02-25 10:48:38,215 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:48:38 INFO - 2016-02-25 10:48:38,215 DEBUG : BROWSER_OUTPUT: __metrics 10:48:38 INFO - 2016-02-25 10:48:38,364 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:48:39 INFO - 2016-02-25 10:48:39,121 INFO : Browser initialized. 10:48:39 INFO - 2016-02-25 10:48:39,122 INFO : Running cycle 1/1 for v8_7 test... 10:48:39 INFO - 2016-02-25 10:48:39,122 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbieZqL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:48:50 INFO - 2016-02-25 10:48:50,797 DEBUG : BROWSER_OUTPUT: RSS: Main: 134381568 10:48:50 INFO - 2016-02-25 10:48:50,797 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,154 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49143/tests/v8_7/run.html (next: http://localhost:49143/tests/v8_7/run.html) 10:49:12 INFO - 2016-02-25 10:49:12,412 DEBUG : BROWSER_OUTPUT: RSS: Main: 424534016 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |0;Richards;167.47613465081227 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.33853738701725 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.9400278940028 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5170.103092783505 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |4;RayTrace;973.7098344693281 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |5;Earley;544.6623093681917 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |7;RegExp;24585.365853658535 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |8;Splay;517.063081695967 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 10:49:12 INFO - 2016-02-25 10:49:12,413 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: __startTimestamp1456426152405__endTimestamp 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.48 Median:167.48 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: Values: 167.5 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.34 Median:164.34 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: Values: 164.3 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.94 Median:278.94 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: Values: 278.9 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,414 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5170.10 Median:5170.10 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: Values: 5170.1 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:973.71 Median:973.71 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: Values: 973.7 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:544.66 Median:544.66 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: Values: 544.7 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: Values: 10526.3 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24585.37 Median:24585.37 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: Values: 24585.4 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:517.06 Median:517.06 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,415 DEBUG : BROWSER_OUTPUT: Values: 517.1 10:49:12 INFO - 2016-02-25 10:49:12,416 DEBUG : BROWSER_OUTPUT: 10:49:12 INFO - 2016-02-25 10:49:12,416 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 10:49:12 INFO - 2016-02-25 10:49:12,416 DEBUG : BROWSER_OUTPUT: Values: 4826.9 10:49:12 INFO - 2016-02-25 10:49:12,416 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:49:12 INFO - 2016-02-25 10:49:12,416 DEBUG : BROWSER_OUTPUT: 10:49:13 INFO - 2016-02-25 10:49:13,101 INFO : Browser exited with error code: 0 10:49:13 INFO - 2016-02-25 10:49:13,118 INFO : Completed test v8_7 (00:00:38) 10:49:13 INFO - 2016-02-25 10:49:13,480 INFO : Completed test suite (00:20:06) 10:49:13 INFO - 2016-02-25 10:49:13,480 DEBUG : Working with test: dromaeo_css 10:49:13 INFO - 2016-02-25 10:49:13,481 DEBUG : Generating results file: dromaeo_css 10:49:13 INFO - 2016-02-25 10:49:13,482 DEBUG : Working with test: kraken 10:49:13 INFO - 2016-02-25 10:49:13,482 DEBUG : Generating results file: kraken 10:49:13 INFO - 2016-02-25 10:49:13,483 INFO : javascript benchmark 10:49:13 INFO - 2016-02-25 10:49:13,483 DEBUG : Working with test: v8_7 10:49:13 INFO - 2016-02-25 10:49:13,483 DEBUG : Generating results file: v8_7 10:49:13 INFO - 2016-02-25 10:49:13,484 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:49:13 INFO - 2016-02-25 10:49:13,541 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:49:13 INFO - 2016-02-25 10:49:13,541 DEBUG : process_Request line: dromaeo_css 7237.13 graph.html#tests=[[72,53,35]] 10:49:13 INFO - 2016-02-25 10:49:13,541 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:49:13 INFO - 2016-02-25 10:49:13,574 DEBUG : process_Request line: kraken 1472.20 graph.html#tests=[[232,53,35]] 10:49:13 INFO - 2016-02-25 10:49:13,574 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:49:13 INFO - 2016-02-25 10:49:13,616 DEBUG : process_Request line: v8_7 23230.69 graph.html#tests=[[230,53,35]] 10:49:13 INFO - 2016-02-25 10:49:13,617 INFO : javascript benchmark 10:49:13 INFO - 2016-02-25 10:49:13,621 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3270.7056364927157, 3336.3494118646845, 3354.731621470283, 3357.060023193507, 3379.476181191915, 5442.183672449135, 5450.536704380127, 5454.754536045877, 5461.15352120421, 5489.0, 5454.072807622703, 5471.316085489314, 5479.287692599956, 5495.230667706524, 5495.752691128899, 4888.511148885119, 4975.477574854643, 5013.824516141935, 5040.546350828425, 5071.923921141186, 4920.3111564381015, 4977.00459908018, 4981.875453113683, 4982.252662100677, 4986.077575648498, 4911.5088491150955, 4954.653174277802, 5006.874828129289, 5010.423801262865, 5014.6991180529285, 5061.089003979282, 5104.412992505872, 5107.259447380109, 5111.69329840209, 5137.51193636604, 4976.552110310089, 5022.899542009176, 5068.062408454448, 5069.873253168664, 5073.746312684351, 5152.995165942642, 5175.974120129413, 5242.318498595197, 5302.681839089667, 5304.814331498379, 5783.652980821164, 5806.82579522615, 5833.387494313079, 5836.066229403275, 5842.006858834008, 5700.515456186229, 5887.470127688529, 5892.587518873681, 5898.292204935436, 5902.055671092605, 5389.67661940281, 5408.972955135238, 5473.370562385297, 5481.5340696041285, 5505.862353441156, 5955.285365756137, 5956.315023772322, 5958.553108516879, 5959.880802383928, 5962.0, 5547.362053363834, 5693.743781529841, 5791.478766910998, 5793.333766616809, 5799.536037117105, 5737.5983681141915, 5840.649561026352, 5849.064149736022, 5874.118882167709, 5882.176495290662, 5850.765969361263, 5883.529317654577, 5887.970560147171, 5889.705514724246, 5890.175375447441, 5756.481916627524, 5882.529397648178, 5892.646441213456, 5899.793507227227, 5900.586958912835, 4470.083632855257, 4511.593956543927, 4512.075024619945, 4514.6388288936805, 4517.232070548015, 3990.6009399059863, 4043.4743483347565, 4075.5924407559005, 4079.3880917862557, 4096.426500289962, 5620.831375058755, 5690.089585666274, 5697.914531282034, 5701.458361455655, 5702.173184888251, 5892.911606325909, 5917.497012753965, 5947.851303717355, 5950.077737950624, 5952.226210592682, 5438.5377242934, 5502.642328248651, 5570.108782594765, 5593.216949627055, 5625.690587017753, 5074.23886417033, 5087.771050302746, 5093.898122037538, 5097.286379906816, 5139.357580302462, 5964.463198312173, 6025.969870150709, 6027.57806953509, 6029.517638588903, 6033.78881739137, 5754.482096611325, 5823.970880145572, 5833.82498525045, 5839.0949402842625, 5840.269966254218, 6025.849353766015, 6029.276486821434, 6041.75832966694, 6042.425969532887, 6056.454919057306, 5648.717564121695, 5759.625624334405, 5794.275715535558, 5797.0, 5798.5651076168615, 5840.065589505659, 5957.314908785545, 5966.224390829165, 5966.463018328372, 5980.760769569169, 5718.399568045462, 5723.42765723441, 5724.284464441945, 5724.914126288026, 5737.713114344183, 5579.609427340048, 5621.578381621476, 5724.971375143265, 5732.598718089694, 5742.59801813869, 5565.7217139143695, 5600.327960644586, 5635.690037048002, 5662.688552129673, 5678.574106941914, 5779.8555036124435, 5787.450192231731, 5801.796937107181, 5804.100364443518, 5809.24479817621, 5504.532114770129, 5597.692126933058, 5687.090065589486, 5691.26013618227, 5694.316681998187], "unit": "ms", "name": "dojo.html", "value": 5413.440645630925}, {"lowerIsBetter": false, "replicates": [576.1501784867319, 606.0999415867437, 616.5622408090258, 621.8445388652834, 622.9813105606834, 31626.525602115988, 40271.19457610849, 40341.991450213725, 40346.0, 40423.0, 45284.320735189, 45654.0, 45689.08621827562, 45759.313610295874, 45919.0, 8185.631646575888, 8319.417640765148, 9073.546322683873, 9257.16685498305, 10451.477426128702, 10626.734331641694, 10792.676219713421, 10801.243912926098, 10810.837837432446, 10816.78366432671, 10705.518251678654, 10745.301555398913, 10828.675139745781, 10831.891681083167, 10840.0, 10739.78520429591, 10831.241813073088, 10843.07833834125, 10859.67420977368, 10860.6198783043, 10570.15438764897, 10689.893101068967, 10695.625653102104, 10712.946435267851, 10722.142228621691, 41711.582884171075, 41896.37155442671, 42022.579774202175, 42157.57842421567, 42205.57794422047, 3318.8340582970995, 3383.915402114942, 3578.141246100943, 3718.4050551911564, 4111.629953304187, 29959.251018724488, 30431.39137217243, 30463.847680761675, 30495.0, 31100.0, 3632.527771389729, 3835.5972622874474, 3850.3261929162286, 3855.9807200964096, 3870.1292209252974, 300473.0, 302041.48979255185, 302447.0, 303719.0, 304061.0, 59034.0, 60089.699551501966, 62765.68617156885, 62820.05769913455, 68158.0, 63920.0, 64475.35524644787, 64537.0, 64574.35425645777, 64784.35215647877, 15640.139792311533, 15717.21413929299, 15806.446774362843, 15817.68364632724, 15821.367145314058, 12178.269303841798, 12377.62867113988, 12384.133110682345, 12386.690332741753, 12387.62837114888, 20988.89505552493, 21506.56986860254, 21609.891950540146, 21646.458838528848, 21658.458538536663, 8480.406371553932, 8560.357973151915, 8611.397202195787, 8673.566321683891, 8716.956415217883, 1059.4967390489455, 1129.1362107987395, 1140.686311264409, 1144.8397224388593, 1148.9831499123336, 29147.271318217216, 29352.412951740847, 29986.250343741143, 30024.099277021724, 33595.16012099717, 1075.8924107589335, 1089.4988305379457, 1100.5927806711409, 1166.7491489329834, 1175.735459521601, 13615.931920340336, 14753.262336883328, 14842.109473431628, 16553.586160346087, 17180.91409542944, 427990.0, 431237.0, 432798.0, 434023.0, 434584.0, 56489.71755141198, 57424.42575574274, 57457.0, 57531.0, 57585.71207144021, 1070.4861666400172, 1369.9589012329645, 1458.9854101459061, 1471.801306823565, 1565.412970136199, 29898.402031959675, 30160.24599384945, 34238.82880585581, 34983.475247870796, 35181.29637407289, 53995.46004539904, 54719.72640136933, 54728.0, 54743.0, 54776.17835732387, 311648.0, 312902.0, 314186.0, 314205.0, 314860.0, 45267.320990185835, 46958.0, 47091.0, 47581.524184759095, 48307.758461208876, 45814.0, 45992.7700361496, 46721.0, 47069.29396058993, 47090.058198836516, 38486.42270365891, 38515.229695406495, 38986.80506597449, 39243.60756392399, 40143.0, 38657.613423865405, 38975.415368768925, 39208.411873821344, 39293.41059884047, 39507.209855802146, 37682.246355072195, 38260.808695956344, 38331.808340958116, 38711.419328710654, 38831.61168388393, 33999.66000340064, 34540.827295864365, 34962.47556286607, 35211.0, 35228.295434091684, 26740.7325926746, 26785.464290713688, 26822.0, 27122.32194195161, 27254.593175853017], "unit": "ms", "name": "ext.html", "value": 20398.469971469825}, {"lowerIsBetter": false, "replicates": [360.4989065199375, 363.64726215570903, 364.29690696954884, 369.40525753536804, 371.21858487883, 14637.707245855077, 15078.547643570682, 17091.82908170918, 17632.294708211673, 18121.184546695382, 16603.41888033919, 16708.164591770423, 16934.576635584115, 17591.208395622194, 17619.0, 5505.614606977503, 5659.688717120557, 5661.943380566183, 5663.008973429654, 5666.121751128561, 4215.178040282146, 4296.011917259032, 5564.360098588653, 5845.620034697752, 6141.416565426277, 6026.216591843055, 6042.728077236535, 6064.272287325527, 6088.782243551307, 6093.78671746489, 5293.9735301323635, 5410.97294513525, 5419.512243898029, 5432.7012014339, 5434.184872269171, 5123.333966584358, 5212.791488340462, 5221.791128354861, 5229.11105112128, 5232.502912223371, 12439.502419903192, 12650.936745316214, 13689.0, 15848.44530441429, 15860.127692976881, 2320.709911261092, 2321.338418550705, 2335.334429687548, 2335.9182428615086, 2415.9637605435933, 9347.018563050919, 9500.049995000514, 9527.952360238223, 9529.285303602188, 9795.706128816075, 1900.4773687235981, 1938.806119388064, 1949.2495389275089, 1963.3128405058258, 1966.3314473079079, 59802.70098649523, 59995.0, 60550.0, 61753.691231544006, 62209.37790622081, 9632.90367096334, 9715.805683886283, 9720.222382209406, 9727.9027209727, 9787.951060244653, 12319.63041108768, 12392.938035309766, 12394.0, 12403.441845116991, 12406.379681015913, 7351.8162045949275, 7416.406687465098, 7597.962010189914, 7655.157932627407, 7673.654685539164, 7497.287757663123, 7512.39900807934, 7543.886841697379, 7568.659410326549, 7585.544867307979, 4150.937735933963, 4199.4120823084795, 4212.304969679968, 4219.198352313051, 4230.15396920611, 1613.6127329440849, 1683.511781583327, 1687.3503701074928, 1801.4955812372557, 1833.275856036858, 1011.8785745710562, 1079.2930630437077, 1093.3439936038471, 1128.8814674459227, 1141.3437273567731, 13470.528531501546, 16102.114383708775, 16304.429344972868, 16339.58874026747, 16605.169741512876, 997.835357166074, 1004.4726518577805, 1038.8909164537765, 1041.047441590951, 1062.4846949229618, 9581.185599224005, 9958.850617240747, 9979.351342162736, 10016.54925528353, 10037.146842518321, 571671.0, 587930.0, 636029.0, 640336.0, 649852.0, 81636.59181704173, 82005.17994819976, 83238.16761832425, 83818.58090709508, 85277.99999999876, 1103.7957977774138, 1108.5953626926114, 1142.4687520303098, 1162.5640384855678, 1162.68607475981, 15086.698266034837, 15483.922580387027, 15526.689466210839, 15567.610809729847, 15746.842531574537, 33654.66345336515, 33941.981740547824, 34321.48517772186, 35678.10804729902, 36543.08642283964, 40328.79835600803, 41304.79347603243, 41735.58264417317, 42712.57287427086, 43446.565534345515, 9316.627334906529, 9730.805383892424, 9751.073648003428, 9759.707208783748, 9760.951195243979, 17514.649707006043, 18144.546386340447, 18183.909080454512, 18269.54326141804, 18435.0, 15343.386264549294, 15488.457903973307, 15646.530604081896, 15684.68630627404, 15714.057156570643, 14947.177905215318, 15096.16971066602, 15134.730538922156, 15449.0, 15672.0, 12311.815322769986, 14733.336999834819, 15285.6178595536, 15331.846681533489, 15352.23238838076, 9602.135807777333, 9663.661771837951, 9707.0, 9778.853317200106, 9803.509824508888, 10063.245256605638, 10123.392596443942, 14730.0, 14755.483558075415, 14938.029028113138], "unit": "ms", "name": "jquery.html", "value": 8928.77948337871}, {"lowerIsBetter": false, "replicates": [1538.1463287875229, 1589.237166160243, 1598.2807736518569, 1766.3376233912284, 1869.8410635096016, 7284.526505777121, 7705.614719264036, 7713.3829293656445, 7726.690932362712, 7732.381409487241, 7462.328390444859, 7612.61936903154, 7626.961865190667, 7640.808979775508, 7643.0, 2230.453538882976, 2260.050778672953, 2297.5060362022164, 2298.7011688480475, 2300.7814257645496, 2199.560087982402, 2230.9219177328796, 2231.0852550454315, 2232.0179121186707, 2232.7097477327925, 1961.7743959444633, 1984.8412127029803, 2150.3441450357636, 2207.569523942832, 2251.470904337479, 2224.0547767198955, 2232.5088480534255, 2238.7425446073667, 2244.9999999999836, 2250.066222517645, 2224.1214720185435, 2292.5071109711325, 2292.7707229277107, 2293.0827668932393, 2295.070496448924, 6861.142357205349, 7047.788566342967, 7445.553266804009, 7520.172780994087, 7548.622568871535, 4678.812847486097, 4713.9764301178275, 4718.3158442025715, 4726.480087190406, 4750.524947505256, 2934.706529347048, 2956.127942257039, 2976.4642364374404, 2979.9851000744857, 3021.864016119258, 1020.515255253756, 1025.3335332034162, 1045.6706137566641, 1046.1108058150587, 1055.1822337688277, 10052.195824334109, 10128.696139115838, 10200.489975501196, 10204.183665306758, 10284.999999999925, 4124.443200167999, 4169.666426685858, 4188.560201178893, 4194.622483976395, 4197.643200328006, 8925.955370223108, 8951.239144672645, 8964.282857371394, 8977.640894364153, 9030.0, 4547.408836851188, 4564.0871825635, 4568.1548913451115, 4569.200389931749, 4569.223232050559, 4070.1452694933805, 4077.368007958771, 4078.204750073707, 4088.366303223005, 4090.734102283342, 3480.773749706261, 3533.4876442916147, 3555.839987200582, 3560.038789526813, 3564.215872508044, 1105.4472763618191, 1118.222835129577, 1120.3501968858043, 1133.1388145009853, 1133.8015847226702, 1121.876593574706, 1141.4806263150413, 1149.6321177223208, 1304.6738315421144, 1368.3773882883265, 7314.305141011595, 7331.083614548182, 7381.926180738231, 7407.222241664547, 7506.699732010768, 1166.2128063557066, 1187.8040123379546, 1196.970075748096, 1200.880978244612, 1203.7291609387817, 5659.547236221092, 5981.910271345933, 6022.909656355158, 6061.818145455643, 6070.969645151746, 8237.546934918517, 8907.51008694528, 9040.63837446508, 9048.276137908952, 9083.727488175366, 8574.785630359167, 8873.73378798637, 8983.101689830964, 9007.23438507721, 9080.81838363229, 7350.338469537767, 7448.1434635016585, 7458.179600243969, 7477.775666730006, 7479.401647868158, 7285.599292038989, 7346.5592064476305, 7382.298681625237, 7429.182789893215, 7435.925640743523, 8124.187581242065, 8209.138040505899, 8260.669573217005, 8275.586220689062, 8290.212429819157, 8326.333893288402, 8326.542040187847, 8405.07544170153, 8519.829603408021, 8611.13888611134, 7009.579425234502, 7010.474214433836, 7039.190493093155, 7039.401650859734, 7077.186123595852, 7483.289087536676, 7557.168711441627, 7587.58268295249, 7594.202608726005, 7604.619769011638, 7068.0, 7098.3256590623805, 7124.928750712426, 7141.250168732416, 7162.928370716226, 7035.226125126334, 7091.787246382617, 7092.822679433056, 7179.246179151114, 7180.0, 7168.713251469882, 7212.783616491513, 7233.204347521873, 7234.240404757425, 7242.637868106468, 6624.271330153583, 6687.063811066552, 6691.130153080068, 6695.397414232742, 6700.43046341047, 6634.402903738686, 6664.666766661744, 6670.633115178712, 6670.666466676744, 6679.532432729857], "unit": "ms", "name": "mootools.html", "value": 4473.082765620977}, {"lowerIsBetter": false, "replicates": [343.3716299172515, 346.92887957968617, 349.36241359518885, 351.63781305255594, 352.31242349147584, 5904.0553511438175, 5962.0, 6063.514918806496, 6068.544859135566, 6096.177016102824, 5794.739236734346, 5947.6134051286635, 5971.761129554823, 5977.222961015062, 5994.550408719341, 4680.391549098621, 4691.7184968901975, 4695.483496815347, 4697.013627138305, 4710.269908164223, 4499.41507604011, 4561.863144105682, 4563.657725670571, 4579.267317229244, 4701.788419521113, 4627.259638457847, 4643.419572553431, 4645.117427688745, 4665.370175026362, 4671.88320291992, 4632.675712700112, 4636.907261854744, 4638.768061596924, 4649.535046495357, 4661.6969896956925, 4106.0, 4113.9635880239175, 4117.649999750025, 4126.298529250034, 4137.8758637240935, 5541.889162216773, 5703.714814259312, 5708.20085188074, 5716.514096301819, 5731.369549349568, 5412.269343638598, 5413.729313534347, 5417.729113544307, 5426.430224826416, 5430.70131142787, 6069.332373438918, 6333.651649159295, 6361.872762544769, 6370.235571731376, 6371.0, 4423.270160423526, 4455.4207952966235, 4541.091781643651, 4548.56788605082, 4564.931526027112, 6545.705443255065, 6602.537822352389, 6602.93397066028, 6603.966980165068, 6614.9416093424825, 4677.976610116928, 4678.345031695565, 4687.929681054787, 4688.343631891538, 4692.343071969927, 4682.458015724742, 4714.339992401066, 4752.5485078917445, 4753.120672675566, 4759.095771803346, 5399.325084364455, 5404.0272750905215, 5406.6215364924865, 5408.432114627986, 5412.917416516712, 6407.487401008001, 6408.743650253938, 6411.4229719325485, 6412.775552855721, 6412.807615771534, 1642.5729310379388, 1644.8272931342276, 1646.0617448054495, 1647.5057482755126, 1648.250046228963, 1078.82199437092, 1081.8052750504985, 1096.4243772019754, 1156.9247998880046, 1184.0882520459197, 6252.030935205051, 6258.9374106259265, 6271.43557079865, 6276.215473065867, 6277.780277690259, 6571.441427478718, 6607.702653380609, 6612.437942774822, 6614.305497922745, 6619.0, 6166.568340216231, 6240.937590624127, 6244.718987645476, 6246.812595622138, 6251.749930002749, 3566.7859928404378, 3604.675579197885, 3605.116746397097, 3609.061643972586, 3611.313850368474, 6899.241083480813, 6901.62041087735, 6907.378335949688, 6913.654317284116, 6914.412274956685, 6655.035019922084, 6861.279565645636, 6868.141482314711, 6872.828179295558, 6874.690638921161, 6834.794956151323, 6904.93095069063, 6905.309469053054, 6906.309369063053, 6917.204521479994, 6398.840028999312, 6561.3438656134, 6579.80260592183, 6686.264510903894, 6709.832254193489, 6552.148220731465, 6575.309592492719, 6595.406413422815, 6601.471882249312, 6608.900866486911, 6827.180738311435, 6862.725490980305, 6870.3473170048765, 6873.347032031949, 6880.346367095118, 4448.755318457386, 4563.566461186182, 4572.314152876962, 4616.845788552861, 4662.953370466387, 6624.973129164987, 6628.5691430056895, 6629.4696424284975, 6634.071230027704, 6640.435562977008, 6471.935280647133, 6481.773137940149, 6483.481321494363, 6487.870242595216, 6489.091527186292, 6317.273513546001, 6460.741570337133, 6460.773872914426, 6462.095306656977, 6474.093626892333, 6340.524460665376, 6340.587861788969, 6348.333424990309, 6350.7459701611415, 6403.743850245938, 6543.116679248431, 6546.901796472961, 6554.639494827639, 6556.573822701509, 6557.540972132096, 6261.373862613885, 6391.648459334595, 6392.105105285357, 6395.136656551306, 6395.584287021329], "unit": "ms", "name": "prototype.html", "value": 4875.835094113916}, {"lowerIsBetter": false, "replicates": [810.6878851642116, 825.0511911301999, 825.0635528674954, 835.4026870787386, 877.1973644115634, 14612.342444589993, 14972.401103955855, 15022.774658380133, 15025.248737563119, 15049.774253386207, 13521.0, 13604.455821767142, 13641.386137623806, 13646.863531364683, 13667.384967676453, 698.34006863514, 711.537500624593, 851.6550796927239, 857.3698331726176, 883.0507204754882, 861.1689719420758, 862.7541150772031, 863.6329559937033, 868.1969178509853, 870.0864092702668, 743.1305372713938, 920.4293338130368, 925.7685578605349, 926.4163576946514, 932.7901222225013, 749.4603885202646, 751.3876190904396, 751.5378042503876, 914.6935776514875, 940.3323640215456, 666.6067020457953, 667.0, 673.939345458911, 673.9865202695919, 674.305465370669, 14141.0, 14156.150630962175, 14220.360083796253, 14325.498607548685, 14365.353559089866, 13265.602031939057, 13403.72136736757, 13588.660283492893, 13672.384742686603, 13976.161430314112, 11044.116470682326, 11160.330380177134, 11203.271787333797, 11354.0, 11453.427328633536, 3776.7356285060055, 3874.341361968444, 3887.5723670396233, 3905.218956208741, 3923.8430462781453, 48874.51125488771, 50722.0, 50764.0, 51188.0, 51339.743301283255, 11073.335599864035, 11083.556657733761, 11114.666560003212, 11232.606858759904, 11613.88386116145, 20651.380458586267, 20686.379408617766, 20762.896185518977, 20828.58342833135, 20932.581348373253, 8022.879656805153, 8193.0, 8388.538630375266, 8578.356623253156, 8595.484270943763, 4591.586757191869, 4631.768411579407, 4649.837255696035, 4661.696989695659, 4679.672422930362, 3090.98454507726, 3093.8453077346494, 3093.8917137900066, 3107.766917481198, 3108.2695566541843, 564.6781334639298, 578.4851482180862, 581.1921429213397, 583.4165834165834, 593.8099808061465, 559.6278474814203, 629.7418058595954, 703.4477934821141, 704.4751660013322, 751.1361933776201, 8530.274926631306, 8548.487090774574, 8780.648774048967, 8864.335174861912, 8932.868683035927, 617.6448542088317, 668.1314291421133, 669.0900375489331, 690.0890824112124, 724.5109551053018, 4242.412227662904, 4402.84590039347, 4442.777861106932, 4450.421445212101, 4540.523245059288, 56331.0, 57052.0, 57153.428465715646, 57225.14162287569, 57322.140167897516, 52701.736491318065, 52722.814054386996, 53313.466865331626, 53592.46407535953, 53707.462925370244, 47599.0, 47950.760246198544, 48561.0, 48681.26978095331, 48750.512494874594, 8779.385443018928, 8932.553372331487, 8962.193402593537, 9024.063507814439, 9110.407823491718, 34921.47617785786, 35162.296754064264, 35873.28253434969, 36044.63955360413, 36646.63353366539, 47666.28500572564, 48751.756241219984, 48912.0, 49256.507434925195, 49645.75177124091, 11271.267367621078, 11568.537258509901, 11665.88334116648, 11668.183227174017, 12280.81578776337, 13693.520726774515, 13752.724945500833, 13807.585772426843, 13847.238401887993, 13928.373223204777, 12342.629721108382, 12559.372031398576, 12621.936890315857, 12645.348111535412, 13237.27195004284, 12250.509979601073, 12299.569515066929, 12496.500139994298, 12674.112812103065, 12830.615081547568, 10799.0, 10820.0, 11203.0, 11217.55129794799, 11416.143789215675, 9666.709998700051, 9865.408075515492, 9935.0, 10162.136218421518, 10228.284020118817, 10281.280310378203, 10594.20543459259, 10979.17656175806, 11157.0, 11258.0], "unit": "ms", "name": "yui.html", "value": 6680.941466303746}], "name": "dromaeo_css", "value": 7237.129146093859}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 104.0, 109.0, 110.0, 110.0, 100.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.0}, {"lowerIsBetter": true, "replicates": [158.0, 121.0, 151.0, 160.0, 121.0, 150.0, 156.0, 121.0, 159.0, 120.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.7}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 158.0, 160.0, 161.0, 158.0, 160.0, 162.0, 159.0, 158.0], "unit": "ms", "name": "audio-dft", "value": 159.7}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 71.0, 160.0, 71.0, 71.0, 71.0, 70.0, 163.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 89.2}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 146.0, 92.0, 92.0, 92.0, 134.0, 92.0, 93.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 105.9}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 111.0, 110.0, 109.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [56.0, 57.0, 57.0, 57.0, 57.0, 57.0, 59.0, 59.0, 55.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.0}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.3}, {"lowerIsBetter": true, "replicates": [77.0, 83.0, 86.0, 77.0, 77.0, 85.0, 77.0, 76.0, 77.0, 77.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.2}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 111.0, 111.0, 109.0, 111.0, 117.0, 123.0, 111.0, 119.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 113.3}, {"lowerIsBetter": true, "replicates": [130.0, 140.0, 139.0, 139.0, 141.0, 138.0, 139.0, 151.0, 143.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.9}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 62.0, 64.0, 63.0, 61.0, 63.0, 66.0, 63.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 63.0}], "name": "kraken", "value": 1472.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.47613465081227], "unit": "score", "name": "Richards", "value": 210.7882419999999}, {"lowerIsBetter": false, "replicates": [164.33853738701725], "unit": "score", "name": "DeltaBlue", "value": 402.3280300000001}, {"lowerIsBetter": false, "replicates": [278.9400278940028], "unit": "score", "name": "Encrypt", "value": 954.2588850000001}, {"lowerIsBetter": false, "replicates": [5170.103092783505], "unit": "score", "name": "Decrypt", "value": 51.48466001994015}, {"lowerIsBetter": false, "replicates": [973.7098344693281], "unit": "score", "name": "RayTrace", "value": 759.9687029999998}, {"lowerIsBetter": false, "replicates": [544.6623093681917], "unit": "score", "name": "Earley", "value": 1223.6260680000005}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24585.365853658535], "unit": "score", "name": "RegExp", "value": 37.05395337301591}, {"lowerIsBetter": false, "replicates": [517.063081695967], "unit": "score", "name": "Splay", "value": 157.6035939999999}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23230.691345492487}]} 10:49:13 INFO - RETURN: dromaeo_css: 7237.13 10:49:13 INFO - RETURN: kraken: 1472.20 10:49:13 INFO - RETURN: v8_7: 23230.69 10:49:13 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23230.69"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1472.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7237.13"}}} 10:49:13 INFO - Return code: 0 10:49:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:49:13 INFO - # TBPL SUCCESS # 10:49:13 INFO - Running post-action listener: _resource_record_post_action 10:49:13 INFO - Running post-run listener: _resource_record_post_run 10:49:14 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 421888; Write bytes: 278626304; Read time: 188; Write time: 159656 10:49:14 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 19701760; Read time: 0; Write time: 5800 10:49:14 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 421888; Write bytes: 258924544; Read time: 188; Write time: 153856 10:49:14 INFO - Running post-run listener: _upload_blobber_files 10:49:14 INFO - Blob upload gear active. 10:49:14 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:49:14 INFO - Copying logs to upload dir... 10:49:14 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1261.204767 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-02-25 10:49:14.879335) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:49:14.883058) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009939 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416459/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.05 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:49:14.939016) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:49:14.939371) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1456424203.210160-1690965344 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:49:14.992781) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 10:49:14.993091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 10:49:14.993389) ========= ========= Total master_lag: 0.26 =========