builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-066 starttime: 1450915531.75 results: success (0) buildid: 20151223133015 builduid: fbb692c3ff0043fcbf80bd4bf0420e45 revision: 1cc7f3085826a7bddf4459aa03b1b87cd8cc8a8e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.747853) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.748280) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.748561) ========= 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=004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006256 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.771407) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.771719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.785658) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.785939) ========= 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=004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.025111 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.836921) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.837208) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.837547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.837825) ========= 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=004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-23 16:05:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.4M=0s 2015-12-23 16:05:32 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117607 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.968064) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:31.968394) ========= 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=004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.077520 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:32.056465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:32.056821) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1cc7f3085826a7bddf4459aa03b1b87cd8cc8a8e --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 1cc7f3085826a7bddf4459aa03b1b87cd8cc8a8e --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=004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-23 16:05:32,173 truncating revision to first 12 chars 2015-12-23 16:05:32,174 Setting DEBUG logging. 2015-12-23 16:05:32,174 attempt 1/10 2015-12-23 16:05:32,174 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/1cc7f3085826?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-23 16:05:32,571 unpacking tar archive at: mozilla-inbound-1cc7f3085826/testing/mozharness/ program finished with exit code 0 elapsedTime=0.695875 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:32.767091) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:32.767430) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:32.777026) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:32.777408) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-23 16:05:32.777873) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2015-12-23 16:05:32.778227) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:05:32 INFO - MultiFileLogger online at 20151223 16:05:32 in /builds/slave/test-pgo 16:05:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:05:32 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:05:32 INFO - {'append_to_log': False, 16:05:32 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:05:32 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:05:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:05:32 INFO - 'branch': 'Mozilla-Inbound', 16:05:32 INFO - 'buildbot_json_path': 'buildprops.json', 16:05:32 INFO - 'config_files': ('talos/linux_config.py',), 16:05:32 INFO - 'default_actions': ('clobber', 16:05:32 INFO - 'read-buildbot-config', 16:05:32 INFO - 'download-and-extract', 16:05:32 INFO - 'populate-webroot', 16:05:32 INFO - 'create-virtualenv', 16:05:32 INFO - 'install', 16:05:32 INFO - 'run-tests'), 16:05:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:05:32 INFO - 'download_minidump_stackwalk': True, 16:05:32 INFO - 'download_symbols': 'ondemand', 16:05:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:05:32 INFO - 'tooltool.py': '/tools/tooltool.py', 16:05:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:05:32 INFO - '/tools/misc-python/virtualenv.py')}, 16:05:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:05:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:05:32 INFO - 'installer_path': 'installer.exe', 16:05:32 INFO - 'log_level': 'info', 16:05:32 INFO - 'log_name': 'talos', 16:05:32 INFO - 'log_to_console': True, 16:05:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:05:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:05:32 INFO - 'opt_config_files': (), 16:05:32 INFO - 'pip_index': False, 16:05:32 INFO - 'sps_profile': False, 16:05:32 INFO - 'sps_profile_interval': 0, 16:05:32 INFO - 'suite': 'dromaeojs', 16:05:32 INFO - 'system_bits': '64', 16:05:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:05:32 INFO - 'title': 'talos-linux64-ix-066', 16:05:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:05:32 INFO - 'use_talos_json': True, 16:05:32 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:05:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:05:32 INFO - 'work_dir': 'build'} 16:05:32 INFO - ##### 16:05:33 INFO - ##### Running clobber step. 16:05:33 INFO - ##### 16:05:33 INFO - Running pre-action listener: _resource_record_pre_action 16:05:33 INFO - Running main action method: clobber 16:05:33 INFO - rmtree: /builds/slave/test-pgo/build 16:05:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:05:37 INFO - Running post-action listener: _resource_record_post_action 16:05:37 INFO - ##### 16:05:37 INFO - ##### Running read-buildbot-config step. 16:05:37 INFO - ##### 16:05:37 INFO - Running pre-action listener: _resource_record_pre_action 16:05:37 INFO - Running main action method: read_buildbot_config 16:05:37 INFO - Using buildbot properties: 16:05:37 INFO - { 16:05:37 INFO - "project": "", 16:05:37 INFO - "product": "firefox", 16:05:37 INFO - "script_repo_revision": "production", 16:05:37 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:05:37 INFO - "repository": "", 16:05:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:05:37 INFO - "buildid": "20151223133015", 16:05:37 INFO - "pgo_build": "True", 16:05:37 INFO - "basedir": "/builds/slave/test-pgo", 16:05:37 INFO - "buildnumber": 1368, 16:05:37 INFO - "slavename": "talos-linux64-ix-066", 16:05:37 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:05:37 INFO - "platform": "ubuntu64_hw", 16:05:37 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:05:37 INFO - "branch": "mozilla-inbound", 16:05:37 INFO - "repo_path": "integration/mozilla-inbound", 16:05:37 INFO - "revision": "1cc7f3085826a7bddf4459aa03b1b87cd8cc8a8e", 16:05:37 INFO - "stage_platform": "linux64-pgo", 16:05:37 INFO - "builduid": "fbb692c3ff0043fcbf80bd4bf0420e45", 16:05:37 INFO - "slavebuilddir": "test" 16:05:37 INFO - } 16:05:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 16:05:37 INFO - Running post-action listener: _resource_record_post_action 16:05:37 INFO - ##### 16:05:37 INFO - ##### Running download-and-extract step. 16:05:37 INFO - ##### 16:05:37 INFO - Running pre-action listener: _resource_record_pre_action 16:05:37 INFO - Running main action method: download_and_extract 16:05:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:05: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')]} 16:05:37 INFO - https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 16:05:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/test_packages.json 16:05:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/test_packages.json 16:05:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:05:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:05:38 INFO - Downloaded 1302 bytes. 16:05:38 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:05:38 INFO - Using the following test package requirements: 16:05:38 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:05:38 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:38 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:05:38 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:38 INFO - u'jsshell-linux-x86_64.zip'], 16:05:38 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:38 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:05:38 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:05:38 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:38 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:05:38 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:38 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:05:38 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:38 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:05:38 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:05:38 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:05:38 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:05:38 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:38 INFO - https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:05:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:05:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:05:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:05:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:05:44 INFO - Downloaded 23188227 bytes. 16:05:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 16:05:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:05:44 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:05:46 INFO - Return code: 0 16:05:46 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:05:46 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')]} 16:05:46 INFO - https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:05:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:05:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:05:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:05:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:05:49 INFO - Downloaded 23188227 bytes. 16:05:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 16:05:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:05:49 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:05:50 INFO - Return code: 0 16:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:50 INFO - https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:05:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:05:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:05:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:05:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:05:51 INFO - Downloaded 11132277 bytes. 16:05:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 16:05:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:05:51 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 16:05:51 INFO - Return code: 0 16:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:51 INFO - https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:05:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:05:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:05:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:05:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:05:54 INFO - Downloaded 54876341 bytes. 16:05:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:05:54 INFO - mkdir: /builds/slave/test-pgo/properties 16:05:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:05:54 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:05:54 INFO - Contents: 16:05:54 INFO - build_url:https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:05:54 INFO - Running post-action listener: _resource_record_post_action 16:05:54 INFO - Running post-action listener: set_extra_try_arguments 16:05:54 INFO - ##### 16:05:54 INFO - ##### Running populate-webroot step. 16:05:54 INFO - ##### 16:05:54 INFO - Running pre-action listener: _resource_record_pre_action 16:05:54 INFO - Running main action method: populate_webroot 16:05:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:05:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:05:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:05:54 INFO - '--tppagecycles', 16:05:54 INFO - '7'], 16:05:54 INFO - 'tests': ['tsvgm']}}, 16:05:54 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:05:54 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:05:54 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:05:54 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:05:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:05:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:54 INFO - 'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:05:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:54 INFO - 'tests': ['damp', 'tps']}, 16:05:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:54 INFO - 'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['damp', 'tps']}, 16:05:54 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:05:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:05:54 INFO - 'other': {'tests': ['a11yr', 16:05:54 INFO - 'ts_paint', 16:05:54 INFO - 'tpaint', 16:05:54 INFO - 'sessionrestore', 16:05:54 INFO - 'sessionrestore_no_auto_restore']}, 16:05:54 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['a11yr', 16:05:54 INFO - 'ts_paint', 16:05:54 INFO - 'tpaint', 16:05:54 INFO - 'sessionrestore', 16:05:54 INFO - 'sessionrestore_no_auto_restore']}, 16:05:54 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['a11yr', 16:05:54 INFO - 'ts_paint', 16:05:54 INFO - 'tpaint', 16:05:54 INFO - 'sessionrestore', 16:05:54 INFO - 'sessionrestore_no_auto_restore']}, 16:05:54 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['a11yr', 16:05:54 INFO - 'ts_paint', 16:05:54 INFO - 'tpaint', 16:05:54 INFO - 'sessionrestore', 16:05:54 INFO - 'sessionrestore_no_auto_restore']}, 16:05:54 INFO - 'other_l64': {'tests': ['a11yr', 16:05:54 INFO - 'ts_paint', 16:05:54 INFO - 'tpaint', 16:05:54 INFO - 'sessionrestore', 16:05:54 INFO - 'sessionrestore_no_auto_restore']}, 16:05:54 INFO - 'other_nol64': {'tests': ['a11yr', 16:05:54 INFO - 'ts_paint', 16:05:54 INFO - 'tpaint', 16:05:54 INFO - 'sessionrestore', 16:05:54 INFO - 'sessionrestore_no_auto_restore']}, 16:05:54 INFO - 'svgr': {'tests': ['tsvgx', 16:05:54 INFO - 'tsvgr_opacity', 16:05:54 INFO - 'tart', 16:05:54 INFO - 'tscrollx', 16:05:54 INFO - 'cart']}, 16:05:54 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['tsvgx', 16:05:54 INFO - 'tsvgr_opacity', 16:05:54 INFO - 'tart', 16:05:54 INFO - 'tscrollx', 16:05:54 INFO - 'cart']}, 16:05:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:54 INFO - 'tests': ['tp5o']}, 16:05:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:54 INFO - 'talos_options': ['--e10s'], 16:05:54 INFO - 'tests': ['tp5o']}, 16:05:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:54 INFO - 'talos_options': ['--xperf_path', 16:05:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:05:54 INFO - 'tests': ['tp5n']}, 16:05:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:05:54 INFO - 'talos_options': ['--e10s', 16:05:54 INFO - '--xperf_path', 16:05:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:05:54 INFO - 'tests': ['tp5n']}}, 16:05:54 INFO - 'talos.zip': {'path': '', 16:05:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:05:54 INFO - Running post-action listener: _resource_record_post_action 16:05:54 INFO - ##### 16:05:54 INFO - ##### Running create-virtualenv step. 16:05:54 INFO - ##### 16:05:54 INFO - Running pre-action listener: _resource_record_pre_action 16:05:54 INFO - Running main action method: create_virtualenv 16:05:54 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:05:54 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 16:05:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:05:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:05:55 INFO - Using real prefix '/usr' 16:05:55 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:05:55 INFO - Installing distribute.............................................................................................................................................................................................done. 16:05:59 INFO - Installing pip.................done. 16:05:59 INFO - Return code: 0 16:05:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:05:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 16:05:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:05:59 INFO - Using env: {'DISPLAY': ':0', 16:05:59 INFO - 'HOME': '/home/cltbld', 16:05:59 INFO - 'LANG': 'en_US.UTF-8', 16:05:59 INFO - 'LANGUAGE': 'en_US:en', 16:05:59 INFO - 'LOGNAME': 'cltbld', 16:05:59 INFO - 'MAIL': '/var/mail/cltbld', 16:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:59 INFO - 'MOZ_NO_REMOTE': '1', 16:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:59 INFO - 'NO_EM_RESTART': '1', 16:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:05:59 INFO - 'PWD': '/builds/slave/test-pgo', 16:05:59 INFO - 'SHELL': '/bin/bash', 16:05:59 INFO - 'SHLVL': '1', 16:05:59 INFO - 'TERM': 'linux', 16:05:59 INFO - 'TMOUT': '86400', 16:05:59 INFO - 'USER': 'cltbld', 16:05:59 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:59 INFO - '_': '/tools/buildbot/bin/python'} 16:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:59 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 16:05:59 INFO - Cleaning up... 16:05:59 INFO - Return code: 0 16:05:59 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:05:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:05:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 16:05:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:05:59 INFO - Using env: {'DISPLAY': ':0', 16:05:59 INFO - 'HOME': '/home/cltbld', 16:05:59 INFO - 'LANG': 'en_US.UTF-8', 16:05:59 INFO - 'LANGUAGE': 'en_US:en', 16:05:59 INFO - 'LOGNAME': 'cltbld', 16:05:59 INFO - 'MAIL': '/var/mail/cltbld', 16:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:59 INFO - 'MOZ_NO_REMOTE': '1', 16:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:59 INFO - 'NO_EM_RESTART': '1', 16:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:05:59 INFO - 'PWD': '/builds/slave/test-pgo', 16:05:59 INFO - 'SHELL': '/bin/bash', 16:05:59 INFO - 'SHLVL': '1', 16:05:59 INFO - 'TERM': 'linux', 16:05:59 INFO - 'TMOUT': '86400', 16:05:59 INFO - 'USER': 'cltbld', 16:05:59 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:59 INFO - '_': '/tools/buildbot/bin/python'} 16:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:05:59 INFO - Downloading/unpacking psutil>=0.7.1 16:05:59 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 16:05:59 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 16:05:59 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 16:05:59 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 16:05:59 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 16:05:59 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 16:06:01 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:06:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:06:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:06:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:06:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:06:01 INFO - Installing collected packages: psutil 16:06:01 INFO - Running setup.py install for psutil 16:06:01 INFO - building 'psutil._psutil_linux' extension 16:06:01 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 16:06:01 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 16:06:01 INFO - building 'psutil._psutil_posix' extension 16:06:01 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 16:06:02 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 16:06:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:06:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:06:02 INFO - Successfully installed psutil 16:06:02 INFO - Cleaning up... 16:06:02 INFO - Return code: 0 16:06:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:06:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 16:06:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:06:02 INFO - Using env: {'DISPLAY': ':0', 16:06:02 INFO - 'HOME': '/home/cltbld', 16:06:02 INFO - 'LANG': 'en_US.UTF-8', 16:06:02 INFO - 'LANGUAGE': 'en_US:en', 16:06:02 INFO - 'LOGNAME': 'cltbld', 16:06:02 INFO - 'MAIL': '/var/mail/cltbld', 16:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:02 INFO - 'MOZ_NO_REMOTE': '1', 16:06:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:02 INFO - 'NO_EM_RESTART': '1', 16:06:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:06:02 INFO - 'PWD': '/builds/slave/test-pgo', 16:06:02 INFO - 'SHELL': '/bin/bash', 16:06:02 INFO - 'SHLVL': '1', 16:06:02 INFO - 'TERM': 'linux', 16:06:02 INFO - 'TMOUT': '86400', 16:06:02 INFO - 'USER': 'cltbld', 16:06:02 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:02 INFO - '_': '/tools/buildbot/bin/python'} 16:06:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:06:02 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 16:06:02 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 16:06:02 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 16:06:02 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 16:06:02 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 16:06:02 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 16:06:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:06:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:06:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:06:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:06:04 INFO - Installing collected packages: mozsystemmonitor 16:06:04 INFO - Running setup.py install for mozsystemmonitor 16:06:04 INFO - Successfully installed mozsystemmonitor 16:06:04 INFO - Cleaning up... 16:06:04 INFO - Return code: 0 16:06:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:06:04 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')]} 16:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:04 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')]} 16:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:04 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:06:04 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 16:06:04 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:06:04 INFO - Using env: {'DISPLAY': ':0', 16:06:04 INFO - 'HOME': '/home/cltbld', 16:06:04 INFO - 'LANG': 'en_US.UTF-8', 16:06:04 INFO - 'LANGUAGE': 'en_US:en', 16:06:04 INFO - 'LOGNAME': 'cltbld', 16:06:04 INFO - 'MAIL': '/var/mail/cltbld', 16:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:04 INFO - 'MOZ_NO_REMOTE': '1', 16:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:04 INFO - 'NO_EM_RESTART': '1', 16:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:06:04 INFO - 'PWD': '/builds/slave/test-pgo', 16:06:04 INFO - 'SHELL': '/bin/bash', 16:06:04 INFO - 'SHLVL': '1', 16:06:04 INFO - 'TERM': 'linux', 16:06:04 INFO - 'TMOUT': '86400', 16:06:04 INFO - 'USER': 'cltbld', 16:06:04 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:04 INFO - '_': '/tools/buildbot/bin/python'} 16:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:06 INFO - Downloading blobuploader-1.2.4.tar.gz 16:06:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:06:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:06:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:06:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:06:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:06:06 INFO - Downloading docopt-0.6.1.tar.gz 16:06:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:06:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:06:06 INFO - Installing collected packages: blobuploader, requests, docopt 16:06:06 INFO - Running setup.py install for blobuploader 16:06:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:06:07 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:06:07 INFO - Running setup.py install for requests 16:06:07 INFO - Running setup.py install for docopt 16:06:07 INFO - Successfully installed blobuploader requests docopt 16:06:07 INFO - Cleaning up... 16:06:07 INFO - Return code: 0 16:06:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:06:07 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')]} 16:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:07 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')]} 16:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:06:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:06:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:06:07 INFO - Using env: {'DISPLAY': ':0', 16:06:07 INFO - 'HOME': '/home/cltbld', 16:06:07 INFO - 'LANG': 'en_US.UTF-8', 16:06:07 INFO - 'LANGUAGE': 'en_US:en', 16:06:07 INFO - 'LOGNAME': 'cltbld', 16:06:07 INFO - 'MAIL': '/var/mail/cltbld', 16:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:07 INFO - 'MOZ_NO_REMOTE': '1', 16:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:07 INFO - 'NO_EM_RESTART': '1', 16:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:06:07 INFO - 'PWD': '/builds/slave/test-pgo', 16:06:07 INFO - 'SHELL': '/bin/bash', 16:06:07 INFO - 'SHLVL': '1', 16:06:07 INFO - 'TERM': 'linux', 16:06:07 INFO - 'TMOUT': '86400', 16:06:07 INFO - 'USER': 'cltbld', 16:06:07 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:07 INFO - '_': '/tools/buildbot/bin/python'} 16:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:06:07 INFO - Running setup.py (path:/tmp/pip-Ne5kZb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:06:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:06:07 INFO - Running setup.py (path:/tmp/pip-nVmwZC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:06:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:06:07 INFO - Running setup.py (path:/tmp/pip-uBRbXY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:06:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:06:07 INFO - Running setup.py (path:/tmp/pip-IEiBQi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:06:08 INFO - Running setup.py (path:/tmp/pip-s2sKdy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:06:08 INFO - Running setup.py (path:/tmp/pip-a0fJSi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:06:08 INFO - Running setup.py (path:/tmp/pip-MGIXbB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:06:08 INFO - Running setup.py (path:/tmp/pip-vrgJ_Y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:06:08 INFO - Running setup.py (path:/tmp/pip-ZDmZ9G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:06:08 INFO - Running setup.py (path:/tmp/pip-hsd1nv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:06:08 INFO - Running setup.py (path:/tmp/pip-l6RWD2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:06:08 INFO - Running setup.py (path:/tmp/pip-hvBmWb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:06:08 INFO - Running setup.py (path:/tmp/pip-_D2eVd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:06:08 INFO - Running setup.py (path:/tmp/pip-LFs4YS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:06:08 INFO - Running setup.py (path:/tmp/pip-zu5bft-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:06:08 INFO - Running setup.py (path:/tmp/pip-w5WrDi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:06:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:06:08 INFO - Running setup.py (path:/tmp/pip-uXEONW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:06:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:06:08 INFO - Running setup.py install for manifestparser 16:06:09 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Running setup.py install for mozcrash 16:06:09 INFO - Running setup.py install for mozdebug 16:06:09 INFO - Running setup.py install for mozdevice 16:06:09 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Running setup.py install for mozfile 16:06:09 INFO - Running setup.py install for mozhttpd 16:06:09 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Running setup.py install for mozinfo 16:06:09 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Running setup.py install for mozInstall 16:06:09 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Running setup.py install for mozleak 16:06:09 INFO - Running setup.py install for mozlog 16:06:09 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:06:09 INFO - Running setup.py install for moznetwork 16:06:10 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:06:10 INFO - Running setup.py install for mozprocess 16:06:10 INFO - Running setup.py install for mozprofile 16:06:10 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:06:10 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:06:10 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:06:10 INFO - Running setup.py install for mozrunner 16:06:10 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:06:10 INFO - Running setup.py install for mozscreenshot 16:06:10 INFO - Running setup.py install for moztest 16:06:10 INFO - Running setup.py install for mozversion 16:06:10 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:06:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:06:10 INFO - Cleaning up... 16:06:10 INFO - Return code: 0 16:06:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:06:10 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')]} 16:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:10 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')]} 16:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:06:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:06:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:06:10 INFO - Using env: {'DISPLAY': ':0', 16:06:10 INFO - 'HOME': '/home/cltbld', 16:06:10 INFO - 'LANG': 'en_US.UTF-8', 16:06:10 INFO - 'LANGUAGE': 'en_US:en', 16:06:10 INFO - 'LOGNAME': 'cltbld', 16:06:10 INFO - 'MAIL': '/var/mail/cltbld', 16:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:10 INFO - 'MOZ_NO_REMOTE': '1', 16:06:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:10 INFO - 'NO_EM_RESTART': '1', 16:06:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:06:10 INFO - 'PWD': '/builds/slave/test-pgo', 16:06:10 INFO - 'SHELL': '/bin/bash', 16:06:10 INFO - 'SHLVL': '1', 16:06:10 INFO - 'TERM': 'linux', 16:06:10 INFO - 'TMOUT': '86400', 16:06:10 INFO - 'USER': 'cltbld', 16:06:10 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:10 INFO - '_': '/tools/buildbot/bin/python'} 16:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:06:10 INFO - Running setup.py (path:/tmp/pip-QhM_qU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:06:11 INFO - Running setup.py (path:/tmp/pip-mGHfuP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:06:11 INFO - Running setup.py (path:/tmp/pip-5Lwf2A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:06:11 INFO - Running setup.py (path:/tmp/pip-G46l_5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:06:11 INFO - Running setup.py (path:/tmp/pip-CzUvlV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:06:11 INFO - Running setup.py (path:/tmp/pip-rYJdat-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:06:11 INFO - Running setup.py (path:/tmp/pip-MZrT9n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:06:11 INFO - Running setup.py (path:/tmp/pip-XEaRjv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:06:11 INFO - Running setup.py (path:/tmp/pip-_8svlw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:06:11 INFO - Running setup.py (path:/tmp/pip-7aEPIz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:06:11 INFO - Running setup.py (path:/tmp/pip-kgtATB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:06:11 INFO - Running setup.py (path:/tmp/pip-Z4HQg2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:06:11 INFO - Running setup.py (path:/tmp/pip-xYmKuv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:06:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:06:11 INFO - Running setup.py (path:/tmp/pip-k1lQN8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:06:11 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)) 16:06:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:06:11 INFO - Running setup.py (path:/tmp/pip-VFzk_u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:06:12 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)) 16:06:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:06:12 INFO - Running setup.py (path:/tmp/pip-G_QwyC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:06:12 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)) 16:06:12 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:06:12 INFO - Running setup.py (path:/tmp/pip-RFT7Gc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:06:12 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)) 16:06:12 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)) 16:06:12 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)) 16:06:12 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)) 16:06:12 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)) 16:06:12 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)) 16:06:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:06:12 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 16:06:12 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 16:06:12 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 16:06:12 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 16:06:12 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 16:06:12 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 16:06:13 INFO - Downloading blessings-1.5.1.tar.gz 16:06:13 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 16:06:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:06:13 INFO - Installing collected packages: blessings 16:06:13 INFO - Running setup.py install for blessings 16:06:14 INFO - Successfully installed blessings 16:06:14 INFO - Cleaning up... 16:06:14 INFO - Return code: 0 16:06:14 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:06:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:06:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:06:14 INFO - Reading from file tmpfile_stdout 16:06:14 INFO - Current package versions: 16:06:14 INFO - argparse == 1.2.1 16:06:14 INFO - blessings == 1.5.1 16:06:14 INFO - blobuploader == 1.2.4 16:06:14 INFO - docopt == 0.6.1 16:06:14 INFO - manifestparser == 1.1 16:06:14 INFO - mozInstall == 1.12 16:06:14 INFO - mozcrash == 0.16 16:06:14 INFO - mozdebug == 0.1 16:06:14 INFO - mozdevice == 0.47 16:06:14 INFO - mozfile == 1.2 16:06:14 INFO - mozhttpd == 0.7 16:06:14 INFO - mozinfo == 0.9 16:06:14 INFO - mozleak == 0.1 16:06:14 INFO - mozlog == 3.1 16:06:14 INFO - moznetwork == 0.27 16:06:14 INFO - mozprocess == 0.22 16:06:14 INFO - mozprofile == 0.28 16:06:14 INFO - mozrunner == 6.11 16:06:14 INFO - mozscreenshot == 0.1 16:06:14 INFO - mozsystemmonitor == 0.0 16:06:14 INFO - moztest == 0.7 16:06:14 INFO - mozversion == 1.4 16:06:14 INFO - psutil == 3.1.1 16:06:14 INFO - requests == 1.2.3 16:06:14 INFO - wsgiref == 0.1.2 16:06:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:06: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')]} 16:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06: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')]} 16:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:06:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 16:06:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:06:14 INFO - Using env: {'DISPLAY': ':0', 16:06:14 INFO - 'HOME': '/home/cltbld', 16:06:14 INFO - 'LANG': 'en_US.UTF-8', 16:06:14 INFO - 'LANGUAGE': 'en_US:en', 16:06:14 INFO - 'LOGNAME': 'cltbld', 16:06:14 INFO - 'MAIL': '/var/mail/cltbld', 16:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:14 INFO - 'MOZ_NO_REMOTE': '1', 16:06:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:14 INFO - 'NO_EM_RESTART': '1', 16:06:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:06:14 INFO - 'PWD': '/builds/slave/test-pgo', 16:06:14 INFO - 'SHELL': '/bin/bash', 16:06:14 INFO - 'SHLVL': '1', 16:06:14 INFO - 'TERM': 'linux', 16:06:14 INFO - 'TMOUT': '86400', 16:06:14 INFO - 'USER': 'cltbld', 16:06:14 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:14 INFO - '_': '/tools/buildbot/bin/python'} 16:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 16:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 16:06:14 INFO - Cleaning up... 16:06:14 INFO - Return code: 0 16:06:14 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:06: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')]} 16:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06: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')]} 16:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:06:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 16:06:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:06:14 INFO - Using env: {'DISPLAY': ':0', 16:06:14 INFO - 'HOME': '/home/cltbld', 16:06:14 INFO - 'LANG': 'en_US.UTF-8', 16:06:14 INFO - 'LANGUAGE': 'en_US:en', 16:06:14 INFO - 'LOGNAME': 'cltbld', 16:06:14 INFO - 'MAIL': '/var/mail/cltbld', 16:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:14 INFO - 'MOZ_NO_REMOTE': '1', 16:06:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:14 INFO - 'NO_EM_RESTART': '1', 16:06:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:06:14 INFO - 'PWD': '/builds/slave/test-pgo', 16:06:14 INFO - 'SHELL': '/bin/bash', 16:06:14 INFO - 'SHLVL': '1', 16:06:14 INFO - 'TERM': 'linux', 16:06:14 INFO - 'TMOUT': '86400', 16:06:14 INFO - 'USER': 'cltbld', 16:06:14 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:14 INFO - '_': '/tools/buildbot/bin/python'} 16:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:14 INFO - Downloading/unpacking jsonschema 16:06:14 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 16:06:14 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 16:06:14 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 16:06:14 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 16:06:14 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 16:06:14 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 16:06:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:06:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 16:06:16 INFO - Downloading/unpacking functools32 (from jsonschema) 16:06:16 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 16:06:16 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 16:06:16 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 16:06:16 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 16:06:16 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 16:06:16 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 16:06:16 INFO - Downloading functools32-3.2.3-2.tar.gz 16:06:16 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:06:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:06:16 INFO - warning: no files found matching '*.txt' 16:06:16 INFO - no previously-included directories found matching 'build' 16:06:16 INFO - no previously-included directories found matching 'dist' 16:06:16 INFO - no previously-included directories found matching '.git*' 16:06:16 INFO - Installing collected packages: jsonschema, functools32 16:06:16 INFO - Running setup.py install for functools32 16:06:16 INFO - warning: no files found matching '*.txt' 16:06:16 INFO - no previously-included directories found matching 'build' 16:06:16 INFO - no previously-included directories found matching 'dist' 16:06:16 INFO - no previously-included directories found matching '.git*' 16:06:16 INFO - Successfully installed jsonschema functools32 16:06:16 INFO - Cleaning up... 16:06:16 INFO - Return code: 0 16:06:16 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:06: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')]} 16:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:06:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06: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')]} 16:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:06:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:06:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f10a848af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x191b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19ea230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, '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': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', '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 16:06:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 16:06:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:06:16 INFO - Using env: {'DISPLAY': ':0', 16:06:16 INFO - 'HOME': '/home/cltbld', 16:06:16 INFO - 'LANG': 'en_US.UTF-8', 16:06:16 INFO - 'LANGUAGE': 'en_US:en', 16:06:16 INFO - 'LOGNAME': 'cltbld', 16:06:16 INFO - 'MAIL': '/var/mail/cltbld', 16:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:16 INFO - 'MOZ_NO_REMOTE': '1', 16:06:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:16 INFO - 'NO_EM_RESTART': '1', 16:06:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:06:16 INFO - 'PWD': '/builds/slave/test-pgo', 16:06:16 INFO - 'SHELL': '/bin/bash', 16:06:16 INFO - 'SHLVL': '1', 16:06:16 INFO - 'TERM': 'linux', 16:06:16 INFO - 'TMOUT': '86400', 16:06:16 INFO - 'USER': 'cltbld', 16:06:16 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:16 INFO - '_': '/tools/buildbot/bin/python'} 16:06:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:06:17 INFO - Downloading/unpacking flake8 16:06:17 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 16:06:17 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 16:06:17 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 16:06:17 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 16:06:17 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 16:06:17 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 16:06:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:06:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 16:06:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:06:18 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 16:06:18 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 16:06:18 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 16:06:18 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 16:06:18 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 16:06:18 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 16:06:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:06:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 16:06:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:06:18 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 16:06:18 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 16:06:18 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 16:06:18 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 16:06:18 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 16:06:18 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 16:06:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 16:06:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:06:19 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 16:06:19 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 16:06:19 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 16:06:19 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 16:06:19 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 16:06:19 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 16:06:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 16:06:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:06:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:06:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:06:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:06:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:06:19 INFO - no previously-included directories found matching 'docs/_build' 16:06:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:06:19 INFO - Running setup.py install for pep8 16:06:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:06:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:06:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:06:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:06:19 INFO - no previously-included directories found matching 'docs/_build' 16:06:19 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:06:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:06:19 INFO - Cleaning up... 16:06:19 INFO - Return code: 0 16:06:19 INFO - Running post-action listener: _resource_record_post_action 16:06:19 INFO - Running post-action listener: _start_resource_monitoring 16:06:19 INFO - Starting resource monitoring. 16:06:19 INFO - ##### 16:06:19 INFO - ##### Running install step. 16:06:19 INFO - ##### 16:06:19 INFO - Running pre-action listener: _resource_record_pre_action 16:06:19 INFO - Running main action method: install 16:06:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:06:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:06:19 INFO - Reading from file tmpfile_stdout 16:06:19 INFO - Detecting whether we're running mozinstall >=1.0... 16:06:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:06:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:06:19 INFO - Reading from file tmpfile_stdout 16:06:19 INFO - Output received: 16:06:19 INFO - Usage: mozinstall [options] installer 16:06:19 INFO - Options: 16:06:19 INFO - -h, --help show this help message and exit 16:06:19 INFO - -d DEST, --destination=DEST 16:06:19 INFO - Directory to install application into. [default: 16:06:19 INFO - "/builds/slave/test-pgo"] 16:06:19 INFO - --app=APP Application being installed. [default: firefox] 16:06:19 INFO - mkdir: /builds/slave/test-pgo/build/application 16:06:19 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 16:06:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 16:06:35 INFO - Reading from file tmpfile_stdout 16:06:35 INFO - Output received: 16:06:35 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:06:35 INFO - Running post-action listener: _resource_record_post_action 16:06:35 INFO - ##### 16:06:35 INFO - ##### Running run-tests step. 16:06:35 INFO - ##### 16:06:35 INFO - Running pre-action listener: _resource_record_pre_action 16:06:35 INFO - Running main action method: run_tests 16:06:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:06:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:06:35 INFO - Python 2.7.3 16:06:35 INFO - Return code: 0 16:06:35 INFO - grabbing minidump binary from tooltool 16:06:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:06:35 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 0x1a7c7f0>, '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 0x19e82d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19e8760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:06:35 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 16:06:35 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 16:06:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:06:35 INFO - Return code: 0 16:06:35 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:06:35 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:06:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:06:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:06:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:06:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:06:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:06:36 INFO - Return code: 0 16:06:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 16:06:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 16:06:36 INFO - Using env: {'DISPLAY': ':0', 16:06:36 INFO - 'HOME': '/home/cltbld', 16:06:36 INFO - 'LANG': 'en_US.UTF-8', 16:06:36 INFO - 'LANGUAGE': 'en_US:en', 16:06:36 INFO - 'LOGNAME': 'cltbld', 16:06:36 INFO - 'MAIL': '/var/mail/cltbld', 16:06:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:06:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:06:36 INFO - 'MOZ_NO_REMOTE': '1', 16:06:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:06:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:06:36 INFO - 'NO_EM_RESTART': '1', 16:06:36 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:06:36 INFO - 'PWD': '/builds/slave/test-pgo', 16:06:36 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:06:36 INFO - 'SHELL': '/bin/bash', 16:06:36 INFO - 'SHLVL': '1', 16:06:36 INFO - 'TERM': 'linux', 16:06:36 INFO - 'TMOUT': '86400', 16:06:36 INFO - 'USER': 'cltbld', 16:06:36 INFO - 'XDG_SESSION_COOKIE': '004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029', 16:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:06:36 INFO - '_': '/tools/buildbot/bin/python'} 16:06:36 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:06:36 INFO - mozversion application_buildid: 20151223133015 16:06:36 INFO - mozversion application_changeset: 1cc7f3085826a7bddf4459aa03b1b87cd8cc8a8e 16:06:36 INFO - mozversion application_display_name: Nightly 16:06:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:06:36 INFO - mozversion application_name: Firefox 16:06:36 INFO - mozversion application_remotingname: firefox 16:06:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:06:36 INFO - mozversion application_vendor: Mozilla 16:06:36 INFO - mozversion application_version: 46.0a1 16:06:36 INFO - mozversion platform_buildid: 20151223133015 16:06:36 INFO - mozversion platform_changeset: 1cc7f3085826a7bddf4459aa03b1b87cd8cc8a8e 16:06:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:06:36 INFO - mozversion platform_version: 46.0a1 16:06:36 INFO - using testdate: 1450915596 16:06:36 INFO - actual date: 1450915596 16:06:37 INFO - starting webserver on 'localhost:38694' 16:06:37 INFO - SUITE-START | Running 3 tests 16:06:37 INFO - TEST-START | dromaeo_css 16:06:37 INFO - operating with platform_type : linux_ 16:06:37 INFO - Initialising browser for dromaeo_css test... 16:06:37 INFO - TEST-INFO | started process 7069 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3TQDAW/profile http://localhost:38694/getInfo.html) 16:06:41 INFO - PROCESS | 7069 | __metrics Screen width/height:1600/1200 16:06:41 INFO - PROCESS | 7069 | colorDepth:24 16:06:41 INFO - PROCESS | 7069 | Browser inner width/height: 1024/697 16:06:41 INFO - PROCESS | 7069 | __metrics 16:06:41 INFO - PROCESS | 7069 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:06:41 INFO - PROCESS | 7069 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:06:42 INFO - TEST-INFO | 7069: exit 0 16:06:42 INFO - Browser initialized. 16:06:42 INFO - Running cycle 1/1 for dromaeo_css test... 16:06:42 INFO - TEST-INFO | started process 7161 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp3TQDAW/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:06:54 INFO - PROCESS | 7161 | RSS: Main: 151072768 16:06:54 INFO - PROCESS | 7161 | 16:07:44 INFO - PROCESS | 7161 | 1450915664845 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] 16:09:54 INFO - PROCESS | 7161 | Cycle 1(1): loaded http://localhost:38694/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38694/tests/dromaeo/cssquery-ext.html) 16:09:55 INFO - PROCESS | 7161 | RSS: Main: 275402752 16:09:55 INFO - PROCESS | 7161 | 16:12:57 INFO - PROCESS | 7161 | Cycle 1(1): loaded http://localhost:38694/tests/dromaeo/cssquery-ext.html (next: http://localhost:38694/tests/dromaeo/cssquery-jquery.html) 16:12:58 INFO - PROCESS | 7161 | RSS: Main: 233873408 16:12:58 INFO - PROCESS | 7161 | 16:16:00 INFO - PROCESS | 7161 | Cycle 1(1): loaded http://localhost:38694/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38694/tests/dromaeo/cssquery-mootools.html) 16:16:01 INFO - PROCESS | 7161 | RSS: Main: 231120896 16:16:01 INFO - PROCESS | 7161 | 16:19:02 INFO - PROCESS | 7161 | Cycle 1(1): loaded http://localhost:38694/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38694/tests/dromaeo/cssquery-prototype.html) 16:19:03 INFO - PROCESS | 7161 | RSS: Main: 214822912 16:19:03 INFO - PROCESS | 7161 | 16:22:05 INFO - PROCESS | 7161 | Cycle 1(1): loaded http://localhost:38694/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38694/tests/dromaeo/cssquery-yui.html) 16:22:06 INFO - PROCESS | 7161 | RSS: Main: 216535040 16:22:06 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | Cycle 1(1): loaded http://localhost:38694/tests/dromaeo/cssquery-yui.html (next: http://localhost:38694/tests/dromaeo/cssquery-dojo.html) 16:25:09 INFO - PROCESS | 7161 | RSS: Main: 288337920 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | __start_tp_report 16:25:09 INFO - PROCESS | 7161 | _x_x_mozilla_page_load 16:25:09 INFO - PROCESS | 7161 | _x_x_mozilla_page_load_details 16:25:09 INFO - PROCESS | 7161 | |i|pagename|runs| 16:25:09 INFO - PROCESS | 7161 | |0;dojo.html;3101.286704058067;3171.5242713592975;3218.147190994387;3220.855061522231;3253.2517520970177;5063.797448102081;5134.178531434971;5266.183741520061;5268.104422248218;5284.260203571494;5198.4801519848;5242.501962313584;5269.76286067127;5295.7881684732565;5298.841034768953;4728.598069164125;4781.258904869751;4781.402324709411;4872.4640289568115;4873.902521949559;4814.8314808981695;4827.300041493982;4889.535494128052;4896;4903.190973489388;4717.221658426355;4721.480637129913;4806.87982800431;4815.15734746418;4830.855074347775;4784.856454306377;4797.18447863864;4800.495947925488;4807.78364973577;4855.902881942377;4692.6245900327895;4777.737224452654;4791.664583479158;4810.39870016248;4831.396075490564;5009.674371165899;5072.0616685912855;5088.7964481420695;5094.490550944913;5119.38567371914;5637.520810731052;5642.520385767215;5645.717714114319;5648.209250704905;5651.660900345993;5521.806736764234;5633.80281690143;5653.4911857932575;5678.488935995739;5688.772449102031;5247.68513889164;5255.894882102375;5296.92054619181;5298.231756395301;5324.680519168863;5674.347450043216;5687.60186786921;5694.430556944272;5695.572832037614;5699.316082070095;5393.352797664227;5473.343198816088;5501.394846566875;5502.889942201214;5503.394626591072;5544.306961629796;5565.721713914288;5566.944330556724;5582.581306402036;5628;5603.523700485504;5607.971960140173;5613.522850557667;5614.494695477427;5620.437956204347;5513.972430137904;5564.610477266552;5623.915641265303;5626.718664066781;5631.80288689902;4364.061726728705;4364.716293440916;4388.868333949987;4408.735475871458;4422.027154026109;3869.5163104611925;3875.670568001695;3883.0292426893275;3885.261800257942;3923.3722604383734;5176.042432150064;5190.6107041972;5191.428942816287;5238.214267859851;5265.868353291122;5521.144222645416;5654.717264136777;5732.484076433141;5744.425557444222;5778.566607504454;5206.55744261742;5206.661566998208;5206.71363075027;5216.686998780009;5246.265522826808;4872.098661747517;4881.975590122027;4882.365292512021;4883.731394773251;4922.286268491121;5666.065099258576;5738.053221218453;5755.539556835443;5794.333651630032;5902.616329938626;5653.519450846724;5658.5190258826815;5686.886262274814;5687.203791469115;5692.715364231854;5777;5823.242978412949;5833.883322333614;5835.533157347487;5836.737346819318;5355.732213389393;5450.1007333789485;5451.48210919962;5481.643693159932;5580.665160090245;5669.603127781016;5683.033884239772;5698.287714035746;5702.6293290935955;5705.058665320175;5536.806211782568;5557.388687244319;5558.471945165203;5558.583106266965;5568.582356323373;5394.433584473731;5501;5512.614117011771;5513.779448822002;5659.886802264014;5426.972865135649;5427.782888684408;5428.83713488596;5430.782768689208;5434.809781657623;5525.557955363481;5539.58453116026;5550.639208451437;5565.026120428828;5615.326160860723;5336.0395128875625;5396.784128634968;5427.511523962862;5451.100568406168;5542.501174894279 16:25:09 INFO - PROCESS | 7161 | |1;ext.html;559.4852735483355;567.3078843810549;586.7271718650829;606.2573347649129;616.045130048425;27357.86321068397;28075.719242807565;35114.29771404574;35389;35415;39054.609453905454;39418.80290598543;39448.80275598625;39527;39544;7362.852742945138;8262.917370826304;8493.98072231333;8494.40539162259;8497.150284971514;7380.557166569996;9947.801043979116;10068.194544436428;10090.142337901287;10145.137663298628;9941.552630131662;10022.54898529568;10037.247206459508;10037.899621003806;10041.096301332878;7383.889241661379;7397.075365579302;9893.070851184022;9962.601495940155;10003.549840257207;9779.46212958287;9827.754306142333;9837.655682051094;9894.158996485306;9920;35819.4627080594;36347.63652363469;36354.09114772126;36362.272754545025;36398.454023189675;3273.8035717857006;3293.8517766700315;3365.562476878015;3366.848491817874;3396.0660818275173;27125.1862444127;29516.114516564536;29573.408531829456;29677.258068548243;30584.54123188154;3863.613638636114;3881.4639176284713;3937.3503371943593;3961.861334853295;4049.2913740095364;262024;262091;263524.6823765888;263584;263607;47775.76112119487;48326.75836620794;54811.72594137004;55153.72423137858;55189.44810551843;50785.49214507882;50965.49034509682;51188.48811511837;51235.487645123816;51298;13071.607851764462;13212.33938303081;13227.669308267177;13229.933850330686;13239.801402978963;9641.662541811143;9659.082387173208;9661.806763864824;9674.467904265333;9680;17818.821811781974;18319.267229310946;18513.166907489194;18568.628627427377;18583.53541161482;7220.566765994057;7421.406287496988;7467.327940485382;7476.663550140148;7507.436942229246;944.3106532231509;951.2057432044182;970.5680971967407;1015.1067060986285;1057.5981127171779;28373.858130709214;28483.43033139367;28569.57145642817;28589.85705071503;28796;998.4209158687944;1015.1726343030407;1055.894410558938;1107.5514416661265;1125.6904351303294;13192.670183245496;13238.867611323763;13417.597472075853;13495.25776082325;13500.257485838372;267328;270003;270978;271028;271130;46092.3086153708;46291.76854115708;46311.76844115825;46316;50420.24369634459;1325.3042152869627;1327.462377737018;1337.6522104252965;1349.8785109340206;1352.4657760184673;23433.41416464602;24149.034038638256;26020.479590408464;29096.854515728133;30025.249368765955;43764;44114.55885441233;44233.778831105636;44268.335974961046;44284.77857610691;241951;243736;243775;244240;282372;24965.251042468753;29099.563506547842;29293.70706292995;29513.557296640138;29602.259943501584;34292.65707342895;38226.426603601525;39262;39272.41091383689;39302.80348598239;32303.70785168661;33132;33428;33656.83171584126;33737.83131084329;33782;34531.4820277691;35759.106022349646;35776.28447431089;36104.63895361118;31891.840540797148;32453.67546324601;32464.188395290308;32520.67479325271;32554.83722581372;29923.70076299209;30856.228594285323;32604.184895377806;32648.510272346408;32742.181445464055;22629.886850565643;22773.202937897095;22920.885395572914;24958.875205623855;24966.750332496442 16:25:09 INFO - PROCESS | 7161 | |2;jquery.html;355.21320275589596;355.82208895552225;356.8768774772704;360.14106356340136;362.08934529657904;14119.858801411983;14412.207328596924;15969;16270.918645406771;17097.31610735569;15217.315220815062;15229.771553426708;15475.922620386882;15478.5356439307;15672.921635391807;5153.123968925292;5429.592780541456;5430.0768869292315;5430.348358197002;5434.91847622286;3998.56015838259;4075.1034772350035;5274.709890956015;5784.595078344518;6009.1587177795145;5864.413558644144;5937.88124237515;5942.821715348547;5961.076033214858;5972.850678733044;5758.654480731169;5890.499307558863;5892.322382925976;5893.793717219876;5898.262717160355;5218.739063046833;5219.269302297644;5223.764930578133;5225.6603320784025;5228.398734145584;18926.62146757071;19166.329178478827;19341.51646208856;20267.29064482736;20329.898350508152;2078.293380250707;2137.1451419432196;2178.2920550821045;2203.9779602203935;2252.4931890324547;9366.250699943988;9416.387934784218;9495.952520237355;9652.324337296392;9701.417914925127;1803.0984507746127;1830.478313680608;1850.9814901850943;1855.415544103603;1867.6264747050643;59223.40776592222;62039.689801551154;62473;62570.68714656398;62599.37400626027;9447.244220462346;9483.241340692866;9502.809943801085;9509.857352139723;9540.427574345422;12034.338111403957;12065.095117866194;12138.696532586579;12140.69648258783;12166;6675.732970681215;6713.12729345182;6716.227633822173;6742.797716068526;6754.6284954327;6151.7539298428455;6201.596896201732;6203.100550420254;6206.41736357226;6345.397187267295;3707.925841483155;3792.715546334036;3914.9804250978564;3950.7827069510877;3957.9010524736536;1601.3274424741637;1629.4052670775081;1655.5364497940595;1662.2602941690832;1709.2821015173408;915.514777168102;928.5403725155955;950.9995962737574;1004.0160642570322;1032.7624646331385;16297.103659298853;16833.15834208309;17231;17311.220995055002;17407.91296043512;922.7600823785864;933.5425641435784;939.3612343606277;941.3739862991171;951.70497145885;9521.857172142423;9635.13283804461;9663.178629816544;9698.224142068617;9754.853677194847;391868;394418;396312;510153;520594;76757.61621191859;79115;80992.59503702562;82872.58563707264;83201.58399207966;1162.5524173193382;1163.3020187887369;1205.8311836342918;1223.9596343108465;1236.3632729144497;15616;15772.526824195293;15903.125328106602;15974.760378594563;16035.438759643357;34183.31633367368;34216.486752699224;34603.78886738952;35183.64816351906;35986.28027439489;45664.31503527383;45819;46411;46652.53347466482;48186.03627927492;9598.472084035446;9683.41899486033;9686.951565242129;9810.411375317504;9836.852447213441;12514.374281285716;13785;16508.42220522276;17597.736033959245;17784.644307114046;13474.93262533681;14079.648008799863;14176.716465670836;14509.129452232899;14675.853241467876;13831.446742130605;14051.859481405056;14146.080504767157;14555.490557830424;14621;11407;11525.135614828754;14178.361973711415;14327.283635818376;14376.928115359357;8966.551672416483;9049.683261085831;9092.408993415407;9111.817763644822;9133.954330228573;14034.228117453638;14049.776007964161;14766.63083422923;14849.554513364616;15239.161846098128 16:25:09 INFO - PROCESS | 7161 | |3;mootools.html;1336.8195293635356;1420.1550077703769;1428.6928310413261;1559.2515592515592;1734.9999999999993;6609.173853268341;7098.148222213335;7184.461165412596;7195.928040719591;7208.098987626547;7151.964240178791;7308.59802710852;7313.305236002585;7314.707411703539;7333.926660733391;2168.0568952505673;2193.4077798994263;2197.9780202198012;2198.1866709317574;2198.8790616516085;2127.117246342766;2183.1049269799387;2195.2755590655047;2202.8237740980685;2206.8013878750908;2173.0655817998254;2200.19692812124;2216.5345277491747;2236.2508559632543;2238.9888050559725;2178.6405243134864;2199.120351859253;2201.8348623853194;2202.1301585873653;2202.757696653367;2159.470929622237;2193.692882996366;2210.4031911383836;2211.1155537784853;2215.711957445538;7341.5962122083265;7427.851442971164;7431.368333691643;7434.256574342577;7450.552966822008;4197.412362269254;4244.893877653052;4265.786710664454;4284.3145096784365;4291;2968.5250359942306;2988.073697153889;3005.3688725367697;3012.774041946841;3031.1815809731247;1089.8201796703613;1093.9343639381582;1105.9447027648666;1115.654147214366;1128.3117298448021;9790.363626364264;9798.461084640341;9819.263555233314;9853.162481189107;9904.653337133166;3938.428927805452;3965.940510892339;3996.820143093568;4021.7586944783407;4026.1947610477437;8603.182697643713;8636.78408039804;8644.265237454762;8652.697155599524;8653.307735381155;4353.238183317907;4386.122775444924;4387.3857659927635;4387.956120438755;4396.318570621559;3589.3718599245026;3697.2790305890094;3903.1998440319667;3993.940090898639;4014.6386825185873;3388.9661103389144;3418.435958066891;3419.9316013679586;3448.4654878493875;3450.654934506529;1087.1085709718113;1092.63396762084;1099.8735145458215;1102.73534351755;1104.5857803323754;1271.5486002469174;1276.3554405025588;1280.4494067550856;1282.9679258018625;1292.2182079841562;6897;6921.134858142732;7000.47496437759;7006.054182685374;7027.8594428111155;1070.8607880975578;1091.6179337232063;1098.549594666199;1100.7028102412303;1131.932084074958;5301.310829592206;5342.2787923630585;5344.706041167696;5346.786128554814;5348.3849357323625;8469.661213551512;8522.70170544028;8599.269062129664;8669.393142480092;8788;8592.570371481526;8644.913550864536;8667.956660216785;8685.088065753005;8687.609057592423;6880.208775990825;7032.92967070333;7057.941308803721;7110.3600675939415;7117.501774875813;6791.422729068085;6816.693248803716;6820.863582728417;6822.692978816064;6827.863442731217;7571.091469023752;7725.5364678119495;7922.564258965582;7966.16355282687;8017.799555010938;8053.073896501743;8171.346292296721;8204.712835050745;8320.251177394064;8350.248477637042;5569.777208911598;5642.435756424325;6172.197614310111;6318.431341179132;6351.301356850835;6997.930020699728;7013.824654383681;7014.68433920485;7041.155061392666;7047.1895731989425;6383.202099737533;6452.419282264619;6522.858499762618;6544.9672751635935;6627.304133066152;6323.304436511888;6377.4579160771855;6450.48396128318;6598.604083754798;6667.066610674607;6563.113979612882;6604.63674497888;6609.3060228675295;6609.405153536205;6610.6694665267505;5574.637648552831;5606.803761868315;5633.267675202197;5656.23640808502;5667.489925906688;6135.049067394561;6165.075238714228;6167.074938759222;6172.259328880563;6176.413240742122 16:25:09 INFO - PROCESS | 7161 | |4;prototype.html;322.40033537620025;330.86930662388374;332.3502552509843;332.9833508324583;338.3740080850427;5899.498542623876;5933.288005439343;5958.255218097738;5978.222831031965;6026.487748541379;5861.150133230679;5873.823785286438;5876.8236952891375;5881.264841894763;5882.88234235315;4563.931541026887;4575.656825738066;4579.175748365309;4581.587657110876;4587.8623641290815;4485.5962963333295;4555.7949892254765;4560.97719511402;4574.176648203322;4577.473590537081;4515.367848501212;4522.886927826814;4524.298733696266;4526.185286648402;4529.207388706979;4325.5025672047805;4503.369528266046;4531.682782205247;4533.455985281755;4538.954610453903;3871.4192871069286;3910.120222949842;3914.823832927525;3921.941170882439;3926.3325234709882;5555;5571.387147413781;5573.777048918038;5582.8325150245555;5590.133529302964;4982.975085124587;5076.873078173038;5100.974495127538;5143.691378517301;5184.533391994739;6030.6080104793045;6080.270367555878;6144.631322120687;6152.846178845564;6218.751249949996;4338.175746608134;4343.153085148398;4346.217680817452;4354.673399495019;4378.474583050022;6319.146915166486;6486.091947127406;6496.123023391829;6498;6509.381608747209;4478.014836735978;4512.300593408027;4516.254817955;4517.503074661785;4518.570735780096;4571.2000399929875;4571.451425828922;4577.633789296849;4579.885502862388;4581.358609794632;5238.633295669343;5256.106461901486;5294.947050529446;5295.602829787781;5309.548688361532;6149.0776383542825;6302.401271879165;6303.46420554258;6306.779262725782;6316.021016742412;1612.7258366077795;1625.9756103658456;1626.056887005534;1626.9993953718615;1627.9755803662954;1075.3010543147018;1075.725689949058;1085.1264731890747;1095.2990086344746;1129.3054771315747;5924.940750592439;5932.2584676915385;5936.347001829795;5939.970300148471;5966.985612445895;6426.164598602152;6487.610743355413;6489.091527186198;6497.610143391412;6498.187726534184;5896.823095307148;5965.045592705146;5966.075258334965;5968.045112781934;5970.611910225908;3440.776349537322;3522.4188008978745;3522.735794815399;3525.242072954276;3527.0476971217627;6652.268250492442;6747.089142965735;6769.492288078413;6771.28901465339;6784.7964561063245;6403.775867844603;6637.834054148685;6641.900371494431;6643.435307998778;6651.600903945779;6237.781677641261;6594.802155935329;6688.3646053624825;6717.932820671731;6718.664066796739;6488.091667166506;6561.770338038336;6571.310012448624;6580.276169621434;6582.934170658232;6504.479641628753;6509.251436084722;6522.902156467562;6525.0538671893255;6589.341065893373;6684.565503242274;6764.0868482754195;6780.69486873082;6785.08401365829;6796.728130874907;4470.709403888737;4504.572065653758;4508.391367165391;4508.684392092522;4509.90980180388;6560.343965603402;6564.6389448578875;6581;6583.111279977334;6585.1439312889015;6274.592151510137;6388.87222255543;6394.680265986589;6395.520335974914;6404.679766011774;6376.489880809617;6376.872462550816;6381.680915954091;6384.712687928962;6386.042093685798;6251.78118765841;6252.218472690914;6252.374762523711;6252.468540174136;6254.405831446005;6206.751729930752;6364.586301890362;6378.649174295459;6383.2978372378075;6415.9358406417205;6230.283517395557;6240.159153049952;6246.5002799775;6249.125122482947;6252.874942501034 16:25:09 INFO - PROCESS | 7161 | |5;yui.html;831.6922738586724;843.1105184030845;844.2401838345488;849.4605925237477;851.1998721202069;13108.606741797737;13340.332983350818;13341.399637016333;13361.398737056832;13362.13146145501;12142.514299428012;12823.358832058406;12824.230546167213;12827.615171544845;12836.935815320934;652.7030201258416;670.0217682184017;769.3614300130906;790.044046703488;804.3806269172734;791.5844181804541;795.6021989005498;801.3148757812074;802.3180296747747;802.4904185841974;826.2811354121923;833.204289903141;833.6040380819093;834.1366685480529;834.7829564313292;682.7200847652464;697.9755708550201;819.8934138561979;830.4104086098877;833.7707130539151;611.4680228201448;612.6416046612737;612.6783438694677;613.0773186354553;613.0803794308537;13230.73538529224;13231.206127632373;13313.467461301536;13349.532766353132;13363.532276370377;11834.940825295818;11853.407329633485;12019;12068.758624827542;12239.755204895851;10181.643642472553;10318.226133039976;10332.173426125966;10393.740156496073;10545;3630.7640003399692;3666.9633303666888;3696.8890933272046;3709.647583479565;3806.029462487048;46377.768111159225;46442.76778616085;46855.53144468546;46948.29577556339;46966.29550556745;9381.530923453936;9427.151556359824;9441.291903107158;9446.669366572138;9545.90454095464;15857.841421585867;15860.127692976766;15885.761713574075;15895.841041589436;15905.840941590435;7222.891656625155;7271.563706177646;7409.222031686703;7459.962700186465;7572.659230334648;4378.4745830499905;4410.3825464435;4498.775061246924;4567.817287308537;4620;3010.1270631516613;3031.621047369079;3041.5893854329825;3045.0408121441674;3048.801827881225;548.958828087895;564.2241917363625;576.3544829790661;592.943670351316;615.3815415507387;598.141666708275;615.602936106209;625.5402279324693;660.5112216959511;677.6916502991127;8455.492670439793;8535.658573656985;8679.17547832955;8724.34567407447;8859.335549833788;515.9767810448539;563.8308507447831;611.2817439508559;652.2909975863269;653.3825534869503;3767.2277183177384;4244.808983595745;4251.893702657459;4254.276772948606;4327.935080973788;52031;52424.73787631038;52605.7369713149;52686.209706854424;52701.472985269655;45573.5442645576;46470;47168.29247561289;49155.50844491581;49434.752826235635;42279.365809512885;43888;44092.3386149208;44194.77902610466;44474.55525444769;8604.311655067704;8900;8906.821863562822;9024.142706442877;9062.728118156467;33018.66981330156;33020.83489582537;33618.663813361556;33632.327353453285;33647.831760841036;41392.586074138875;42141.367879482445;42208.36687449629;42826.357604635334;42879;9746.9025309746;9917.752056198653;10237.09680624842;10947.726306842393;11264.0425563828;10773.868146954143;11159.72100697489;11496.712582185177;11531.769364612828;11999.940000299943;11160.330380177214;11525.366104864313;11610.6516804496;11671.533138674358;11712.765744685228;10761.784764304826;10864.130869530261;11097.223194376316;11303.152263580429;11492.253003554742;11072.667819965414;11167.106631469625;11240.381779002233;11246.66260012201;11428.457298074445;9176.71945001554;9530.85703714431;9557.713268601952;9568.904310956801;9938.055884690943;8803.471791692518;9635.036496350309;9745.805083898424;9822.705318840446;9828.4102953823 16:25:09 INFO - PROCESS | 7161 | __end_tp_report 16:25:09 INFO - PROCESS | 7161 | __start_cc_report 16:25:09 INFO - PROCESS | 7161 | _x_x_mozilla_cycle_collect,963 16:25:09 INFO - PROCESS | 7161 | __end_cc_report 16:25:09 INFO - PROCESS | 7161 | __startTimestamp1450916709307__endTimestamp 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | ------- Summary: start ------- 16:25:09 INFO - PROCESS | 7161 | Number of tests: 6 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | [#0] dojo.html Cycles:165 Average:5226.82 Median:5427.78 stddev:564.87 (10.4%) stddev-sans-first:541.43 16:25:09 INFO - PROCESS | 7161 | Values: 3101.3 3171.5 3218.1 3220.9 3253.3 5063.8 5134.2 5266.2 5268.1 5284.3 5198.5 5242.5 5269.8 5295.8 5298.8 4728.6 4781.3 4781.4 4872.5 4873.9 4814.8 4827.3 4889.5 4896.0 4903.2 4717.2 4721.5 4806.9 4815.2 4830.9 4784.9 4797.2 4800.5 4807.8 4855.9 4692.6 4777.7 4791.7 4810.4 4831.4 5009.7 5072.1 5088.8 5094.5 5119.4 5637.5 5642.5 5645.7 5648.2 5651.7 5521.8 5633.8 5653.5 5678.5 5688.8 5247.7 5255.9 5296.9 5298.2 5324.7 5674.3 5687.6 5694.4 5695.6 5699.3 5393.4 5473.3 5501.4 5502.9 5503.4 5544.3 5565.7 5566.9 5582.6 5628.0 5603.5 5608.0 5613.5 5614.5 5620.4 5514.0 5564.6 5623.9 5626.7 5631.8 4364.1 4364.7 4388.9 4408.7 4422.0 3869.5 3875.7 3883.0 3885.3 3923.4 5176.0 5190.6 5191.4 5238.2 5265.9 5521.1 5654.7 5732.5 5744.4 5778.6 5206.6 5206.7 5206.7 5216.7 5246.3 4872.1 4882.0 4882.4 4883.7 4922.3 5666.1 5738.1 5755.5 5794.3 5902.6 5653.5 5658.5 5686.9 5687.2 5692.7 5777.0 5823.2 5833.9 5835.5 5836.7 5355.7 5450.1 5451.5 5481.6 5580.7 5669.6 5683.0 5698.3 5702.6 5705.1 5536.8 5557.4 5558.5 5558.6 5568.6 5394.4 5501.0 5512.6 5513.8 5659.9 5427.0 5427.8 5428.8 5430.8 5434.8 5525.6 5539.6 5550.6 5565.0 5615.3 5336.0 5396.8 5427.5 5451.1 5542.5 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | [#1] ext.html Cycles:180 Average:42048.97 Median:18396.78 stddev:68130.13 (370.3%) stddev-sans-first:68250.03 16:25:09 INFO - PROCESS | 7161 | Values: 559.5 567.3 586.7 606.3 616.0 27357.9 28075.7 35114.3 35389.0 35415.0 39054.6 39418.8 39448.8 39527.0 39544.0 7362.9 8262.9 8494.0 8494.4 8497.2 7380.6 9947.8 10068.2 10090.1 10145.1 9941.6 10022.5 10037.2 10037.9 10041.1 7383.9 7397.1 9893.1 9962.6 10003.5 9779.5 9827.8 9837.7 9894.2 9920.0 35819.5 36347.6 36354.1 36362.3 36398.5 3273.8 3293.9 3365.6 3366.8 3396.1 27125.2 29516.1 29573.4 29677.3 30584.5 3863.6 3881.5 3937.4 3961.9 4049.3 262024.0 262091.0 263524.7 263584.0 263607.0 47775.8 48326.8 54811.7 55153.7 55189.4 50785.5 50965.5 51188.5 51235.5 51298.0 13071.6 13212.3 13227.7 13229.9 13239.8 9641.7 9659.1 9661.8 9674.5 9680.0 17818.8 18319.3 18513.2 18568.6 18583.5 7220.6 7421.4 7467.3 7476.7 7507.4 944.3 951.2 970.6 1015.1 1057.6 28373.9 28483.4 28569.6 28589.9 28796.0 998.4 1015.2 1055.9 1107.6 1125.7 13192.7 13238.9 13417.6 13495.3 13500.3 267328.0 270003.0 270978.0 271028.0 271130.0 46092.3 46291.8 46311.8 46316.0 50420.2 1325.3 1327.5 1337.7 1349.9 1352.5 23433.4 24149.0 26020.5 29096.9 30025.2 43764.0 44114.6 44233.8 44268.3 44284.8 241951.0 243736.0 243775.0 244240.0 282372.0 24965.3 29099.6 29293.7 29513.6 29602.3 34292.7 38226.4 39262.0 39272.4 39302.8 32303.7 33132.0 33428.0 33656.8 33737.8 33782.0 34531.5 35759.1 35776.3 36104.6 31891.8 32453.7 32464.2 32520.7 32554.8 29923.7 30856.2 32604.2 32648.5 32742.2 22629.9 22773.2 22920.9 24958.9 24966.8 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | [#2] jquery.html Cycles:180 Average:25962.38 Median:393143.00 stddev:73278.90 (18.6%) stddev-sans-first:73459.24 16:25:09 INFO - PROCESS | 7161 | Values: 355.2 355.8 356.9 360.1 362.1 14119.9 14412.2 15969.0 16270.9 17097.3 15217.3 15229.8 15475.9 15478.5 15672.9 5153.1 5429.6 5430.1 5430.3 5434.9 3998.6 4075.1 5274.7 5784.6 6009.2 5864.4 5937.9 5942.8 5961.1 5972.9 5758.7 5890.5 5892.3 5893.8 5898.3 5218.7 5219.3 5223.8 5225.7 5228.4 18926.6 19166.3 19341.5 20267.3 20329.9 2078.3 2137.1 2178.3 2204.0 2252.5 9366.3 9416.4 9496.0 9652.3 9701.4 1803.1 1830.5 1851.0 1855.4 1867.6 59223.4 62039.7 62473.0 62570.7 62599.4 9447.2 9483.2 9502.8 9509.9 9540.4 12034.3 12065.1 12138.7 12140.7 12166.0 6675.7 6713.1 6716.2 6742.8 6754.6 6151.8 6201.6 6203.1 6206.4 6345.4 3707.9 3792.7 3915.0 3950.8 3957.9 1601.3 1629.4 1655.5 1662.3 1709.3 915.5 928.5 951.0 1004.0 1032.8 16297.1 16833.2 17231.0 17311.2 17407.9 922.8 933.5 939.4 941.4 951.7 9521.9 9635.1 9663.2 9698.2 9754.9 391868.0 394418.0 396312.0 510153.0 520594.0 76757.6 79115.0 80992.6 82872.6 83201.6 1162.6 1163.3 1205.8 1224.0 1236.4 15616.0 15772.5 15903.1 15974.8 16035.4 34183.3 34216.5 34603.8 35183.6 35986.3 45664.3 45819.0 46411.0 46652.5 48186.0 9598.5 9683.4 9687.0 9810.4 9836.9 12514.4 13785.0 16508.4 17597.7 17784.6 13474.9 14079.6 14176.7 14509.1 14675.9 13831.4 14051.9 14146.1 14555.5 14621.0 11407.0 11525.1 14178.4 14327.3 14376.9 8966.6 9049.7 9092.4 9111.8 9134.0 14034.2 14049.8 14766.6 14849.6 15239.2 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | [#3] mootools.html Cycles:180 Average:5113.65 Median:5661.86 stddev:2603.81 (46.0%) stddev-sans-first:2595.63 16:25:09 INFO - PROCESS | 7161 | Values: 1336.8 1420.2 1428.7 1559.3 1735.0 6609.2 7098.1 7184.5 7195.9 7208.1 7152.0 7308.6 7313.3 7314.7 7333.9 2168.1 2193.4 2198.0 2198.2 2198.9 2127.1 2183.1 2195.3 2202.8 2206.8 2173.1 2200.2 2216.5 2236.3 2239.0 2178.6 2199.1 2201.8 2202.1 2202.8 2159.5 2193.7 2210.4 2211.1 2215.7 7341.6 7427.9 7431.4 7434.3 7450.6 4197.4 4244.9 4265.8 4284.3 4291.0 2968.5 2988.1 3005.4 3012.8 3031.2 1089.8 1093.9 1105.9 1115.7 1128.3 9790.4 9798.5 9819.3 9853.2 9904.7 3938.4 3965.9 3996.8 4021.8 4026.2 8603.2 8636.8 8644.3 8652.7 8653.3 4353.2 4386.1 4387.4 4388.0 4396.3 3589.4 3697.3 3903.2 3993.9 4014.6 3389.0 3418.4 3419.9 3448.5 3450.7 1087.1 1092.6 1099.9 1102.7 1104.6 1271.5 1276.4 1280.4 1283.0 1292.2 6897.0 6921.1 7000.5 7006.1 7027.9 1070.9 1091.6 1098.5 1100.7 1131.9 5301.3 5342.3 5344.7 5346.8 5348.4 8469.7 8522.7 8599.3 8669.4 8788.0 8592.6 8644.9 8668.0 8685.1 8687.6 6880.2 7032.9 7057.9 7110.4 7117.5 6791.4 6816.7 6820.9 6822.7 6827.9 7571.1 7725.5 7922.6 7966.2 8017.8 8053.1 8171.3 8204.7 8320.3 8350.2 5569.8 5642.4 6172.2 6318.4 6351.3 6997.9 7013.8 7014.7 7041.2 7047.2 6383.2 6452.4 6522.9 6545.0 6627.3 6323.3 6377.5 6450.5 6598.6 6667.1 6563.1 6604.6 6609.3 6609.4 6610.7 5574.6 5606.8 5633.3 5656.2 5667.5 6135.0 6165.1 6167.1 6172.3 6176.4 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | [#4] prototype.html Cycles:180 Average:5264.37 Median:5938.16 stddev:1583.22 (26.7%) stddev-sans-first:1543.60 16:25:09 INFO - PROCESS | 7161 | Values: 322.4 330.9 332.4 333.0 338.4 5899.5 5933.3 5958.3 5978.2 6026.5 5861.2 5873.8 5876.8 5881.3 5882.9 4563.9 4575.7 4579.2 4581.6 4587.9 4485.6 4555.8 4561.0 4574.2 4577.5 4515.4 4522.9 4524.3 4526.2 4529.2 4325.5 4503.4 4531.7 4533.5 4539.0 3871.4 3910.1 3914.8 3921.9 3926.3 5555.0 5571.4 5573.8 5582.8 5590.1 4983.0 5076.9 5101.0 5143.7 5184.5 6030.6 6080.3 6144.6 6152.8 6218.8 4338.2 4343.2 4346.2 4354.7 4378.5 6319.1 6486.1 6496.1 6498.0 6509.4 4478.0 4512.3 4516.3 4517.5 4518.6 4571.2 4571.5 4577.6 4579.9 4581.4 5238.6 5256.1 5294.9 5295.6 5309.5 6149.1 6302.4 6303.5 6306.8 6316.0 1612.7 1626.0 1626.1 1627.0 1628.0 1075.3 1075.7 1085.1 1095.3 1129.3 5924.9 5932.3 5936.3 5940.0 5967.0 6426.2 6487.6 6489.1 6497.6 6498.2 5896.8 5965.0 5966.1 5968.0 5970.6 3440.8 3522.4 3522.7 3525.2 3527.0 6652.3 6747.1 6769.5 6771.3 6784.8 6403.8 6637.8 6641.9 6643.4 6651.6 6237.8 6594.8 6688.4 6717.9 6718.7 6488.1 6561.8 6571.3 6580.3 6582.9 6504.5 6509.3 6522.9 6525.1 6589.3 6684.6 6764.1 6780.7 6785.1 6796.7 4470.7 4504.6 4508.4 4508.7 4509.9 6560.3 6564.6 6581.0 6583.1 6585.1 6274.6 6388.9 6394.7 6395.5 6404.7 6376.5 6376.9 6381.7 6384.7 6386.0 6251.8 6252.2 6252.4 6252.5 6254.4 6206.8 6364.6 6378.6 6383.3 6415.9 6230.3 6240.2 6246.5 6249.1 6252.9 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - PROCESS | 7161 | [#5] yui.html Cycles:180 Average:13188.03 Median:46456.38 stddev:14900.47 (32.1%) stddev-sans-first:14913.38 16:25:09 INFO - PROCESS | 7161 | Values: 831.7 843.1 844.2 849.5 851.2 13108.6 13340.3 13341.4 13361.4 13362.1 12142.5 12823.4 12824.2 12827.6 12836.9 652.7 670.0 769.4 790.0 804.4 791.6 795.6 801.3 802.3 802.5 826.3 833.2 833.6 834.1 834.8 682.7 698.0 819.9 830.4 833.8 611.5 612.6 612.7 613.1 613.1 13230.7 13231.2 13313.5 13349.5 13363.5 11834.9 11853.4 12019.0 12068.8 12239.8 10181.6 10318.2 10332.2 10393.7 10545.0 3630.8 3667.0 3696.9 3709.6 3806.0 46377.8 46442.8 46855.5 46948.3 46966.3 9381.5 9427.2 9441.3 9446.7 9545.9 15857.8 15860.1 15885.8 15895.8 15905.8 7222.9 7271.6 7409.2 7460.0 7572.7 4378.5 4410.4 4498.8 4567.8 4620.0 3010.1 3031.6 3041.6 3045.0 3048.8 549.0 564.2 576.4 592.9 615.4 598.1 615.6 625.5 660.5 677.7 8455.5 8535.7 8679.2 8724.3 8859.3 516.0 563.8 611.3 652.3 653.4 3767.2 4244.8 4251.9 4254.3 4327.9 52031.0 52424.7 52605.7 52686.2 52701.5 45573.5 46470.0 47168.3 49155.5 49434.8 42279.4 43888.0 44092.3 44194.8 44474.6 8604.3 8900.0 8906.8 9024.1 9062.7 33018.7 33020.8 33618.7 33632.3 33647.8 41392.6 42141.4 42208.4 42826.4 42879.0 9746.9 9917.8 10237.1 10947.7 11264.0 10773.9 11159.7 11496.7 11531.8 11999.9 11160.3 11525.4 11610.7 11671.5 11712.8 10761.8 10864.1 11097.2 11303.2 11492.3 11072.7 11167.1 11240.4 11246.7 11428.5 9176.7 9530.9 9557.7 9568.9 9938.1 8803.5 9635.0 9745.8 9822.7 9828.4 16:25:09 INFO - PROCESS | 7161 | -------- Summary: end -------- 16:25:09 INFO - PROCESS | 7161 | 16:25:09 INFO - TEST-INFO | 7161: exit 0 16:25:10 INFO - TEST-OK | dromaeo_css | took 1113005ms 16:25:10 INFO - TEST-START | kraken 16:25:10 INFO - operating with platform_type : linux_ 16:25:10 INFO - Initialising browser for kraken test... 16:25:10 INFO - TEST-INFO | started process 7345 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqE_2w_/profile http://localhost:38694/getInfo.html) 16:25:14 INFO - PROCESS | 7345 | __metrics Screen width/height:1600/1200 16:25:14 INFO - PROCESS | 7345 | colorDepth:24 16:25:14 INFO - PROCESS | 7345 | Browser inner width/height: 1024/697 16:25:14 INFO - PROCESS | 7345 | __metrics 16:25:14 INFO - PROCESS | 7345 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:25:14 INFO - PROCESS | 7345 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:25:14 ERROR - PROCESS | 7345 | JavaScript error: chrome://browser/content/tab-content.js, line 408: TypeError: content is null 16:25:15 INFO - TEST-INFO | 7345: exit 0 16:25:15 INFO - Browser initialized. 16:25:15 INFO - Running cycle 1/1 for kraken test... 16:25:15 INFO - TEST-INFO | started process 7430 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpqE_2w_/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:25:27 INFO - PROCESS | 7430 | RSS: Main: 150892544 16:25:27 INFO - PROCESS | 7430 | 16:25:29 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38694/tests/kraken/driver.html?testName=audio-beat-detection) 16:25:30 INFO - PROCESS | 7430 | RSS: Main: 186253312 16:25:30 INFO - PROCESS | 7430 | 16:25:32 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38694/tests/kraken/driver.html?testName=audio-dft) 16:25:33 INFO - PROCESS | 7430 | RSS: Main: 176058368 16:25:33 INFO - PROCESS | 7430 | 16:25:36 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38694/tests/kraken/driver.html?testName=audio-fft) 16:25:36 INFO - PROCESS | 7430 | RSS: Main: 161173504 16:25:36 INFO - PROCESS | 7430 | 16:25:38 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38694/tests/kraken/driver.html?testName=audio-oscillator) 16:25:39 INFO - PROCESS | 7430 | RSS: Main: 163213312 16:25:39 INFO - PROCESS | 7430 | 16:25:41 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38694/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:25:42 INFO - PROCESS | 7430 | RSS: Main: 165380096 16:25:42 INFO - PROCESS | 7430 | 16:25:45 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38694/tests/kraken/driver.html?testName=imaging-darkroom) 16:25:45 INFO - PROCESS | 7430 | RSS: Main: 166490112 16:25:45 INFO - PROCESS | 7430 | 16:25:49 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38694/tests/kraken/driver.html?testName=imaging-desaturate) 16:25:49 INFO - PROCESS | 7430 | RSS: Main: 166965248 16:25:49 INFO - PROCESS | 7430 | 16:25:52 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38694/tests/kraken/driver.html?testName=json-parse-financial) 16:25:52 INFO - PROCESS | 7430 | RSS: Main: 164888576 16:25:52 INFO - PROCESS | 7430 | 16:25:54 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38694/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:25:54 INFO - PROCESS | 7430 | RSS: Main: 176504832 16:25:54 INFO - PROCESS | 7430 | 16:25:56 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38694/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:25:57 INFO - PROCESS | 7430 | RSS: Main: 176029696 16:25:57 INFO - PROCESS | 7430 | 16:25:59 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38694/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:26:00 INFO - PROCESS | 7430 | RSS: Main: 185389056 16:26:00 INFO - PROCESS | 7430 | 16:26:02 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38694/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:26:03 INFO - PROCESS | 7430 | RSS: Main: 192741376 16:26:03 INFO - PROCESS | 7430 | 16:26:05 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38694/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:26:06 INFO - PROCESS | 7430 | RSS: Main: 190267392 16:26:06 INFO - PROCESS | 7430 | 16:26:07 INFO - PROCESS | 7430 | Cycle 1(1): loaded http://localhost:38694/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38694/tests/kraken/driver.html?testName=ai-astar) 16:26:08 INFO - PROCESS | 7430 | RSS: Main: 249311232 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | __start_tp_report 16:26:08 INFO - PROCESS | 7430 | _x_x_mozilla_page_load 16:26:08 INFO - PROCESS | 7430 | _x_x_mozilla_page_load_details 16:26:08 INFO - PROCESS | 7430 | |i|pagename|runs| 16:26:08 INFO - PROCESS | 7430 | |0;ai-astar;111;113;106;112;112;112;101;111;111;104 16:26:08 INFO - PROCESS | 7430 | |1;audio-beat-detection;149;125;149;161;123;152;162;124;161;124 16:26:08 INFO - PROCESS | 7430 | |2;audio-dft;160;160;160;161;162;162;160;160;160;159 16:26:08 INFO - PROCESS | 7430 | |3;audio-fft;71;73;83;90;72;72;71;106;89;72 16:26:08 INFO - PROCESS | 7430 | |4;audio-oscillator;95;96;136;97;96;96;138;95;95;139 16:26:08 INFO - PROCESS | 7430 | |5;imaging-gaussian-blur;109;109;109;109;110;109;109;109;109;110 16:26:08 INFO - PROCESS | 7430 | |6;imaging-darkroom;146;146;145;147;145;146;146;145;149;146 16:26:08 INFO - PROCESS | 7430 | |7;imaging-desaturate;95;95;96;97;95;95;96;95;96;95 16:26:08 INFO - PROCESS | 7430 | |8;json-parse-financial;58;58;59;57;58;57;58;66;55;57 16:26:08 INFO - PROCESS | 7430 | |9;json-stringify-tinderbox;66;67;67;68;69;68;68;74;68;68 16:26:08 INFO - PROCESS | 7430 | |10;stanford-crypto-aes;83;85;84;92;84;84;85;85;85;84 16:26:08 INFO - PROCESS | 7430 | |11;stanford-crypto-ccm;118;125;122;123;121;129;123;124;116;117 16:26:08 INFO - PROCESS | 7430 | |12;stanford-crypto-pbkdf2;145;153;149;150;150;139;150;160;150;141 16:26:08 INFO - PROCESS | 7430 | |13;stanford-crypto-sha256-iterative;68;68;69;67;70;68;68;64;67;66 16:26:08 INFO - PROCESS | 7430 | __end_tp_report 16:26:08 INFO - PROCESS | 7430 | __start_cc_report 16:26:08 INFO - PROCESS | 7430 | _x_x_mozilla_cycle_collect,918 16:26:08 INFO - PROCESS | 7430 | __end_cc_report 16:26:08 INFO - PROCESS | 7430 | __startTimestamp1450916768139__endTimestamp 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | ------- Summary: start ------- 16:26:08 INFO - PROCESS | 7430 | Number of tests: 14 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#0] ai-astar Cycles:10 Average:109.30 Median:111.50 stddev:4.11 (3.7%) stddev-sans-first:4.31 16:26:08 INFO - PROCESS | 7430 | Values: 111.0 113.0 106.0 112.0 112.0 112.0 101.0 111.0 111.0 104.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#1] audio-beat-detection Cycles:10 Average:143.00 Median:150.50 stddev:17.02 (11.3%) stddev-sans-first:17.92 16:26:08 INFO - PROCESS | 7430 | Values: 149.0 125.0 149.0 161.0 123.0 152.0 162.0 124.0 161.0 124.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 16:26:08 INFO - PROCESS | 7430 | Values: 160.0 160.0 160.0 161.0 162.0 162.0 160.0 160.0 160.0 159.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#3] audio-fft Cycles:10 Average:79.90 Median:72.50 stddev:11.87 (16.4%) stddev-sans-first:12.15 16:26:08 INFO - PROCESS | 7430 | Values: 71.0 73.0 83.0 90.0 72.0 72.0 71.0 106.0 89.0 72.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#4] audio-oscillator Cycles:10 Average:108.30 Median:95.50 stddev:20.29 (21.2%) stddev-sans-first:20.94 16:26:08 INFO - PROCESS | 7430 | Values: 95.0 96.0 136.0 97.0 96.0 96.0 138.0 95.0 95.0 139.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:26:08 INFO - PROCESS | 7430 | Values: 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 110.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:1.20 (0.8%) stddev-sans-first:1.27 16:26:08 INFO - PROCESS | 7430 | Values: 146.0 146.0 145.0 147.0 145.0 146.0 146.0 145.0 149.0 146.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 16:26:08 INFO - PROCESS | 7430 | Values: 95.0 95.0 96.0 97.0 95.0 95.0 96.0 95.0 96.0 95.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.00 stddev:2.91 (5.0%) stddev-sans-first:3.08 16:26:08 INFO - PROCESS | 7430 | Values: 58.0 58.0 59.0 57.0 58.0 57.0 58.0 66.0 55.0 57.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:68.00 stddev:2.16 (3.2%) stddev-sans-first:2.13 16:26:08 INFO - PROCESS | 7430 | Values: 66.0 67.0 67.0 68.0 69.0 68.0 68.0 74.0 68.0 68.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.00 stddev:2.51 (3.0%) stddev-sans-first:2.55 16:26:08 INFO - PROCESS | 7430 | Values: 83.0 85.0 84.0 92.0 84.0 84.0 85.0 85.0 85.0 84.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:123.00 stddev:3.97 (3.2%) stddev-sans-first:3.96 16:26:08 INFO - PROCESS | 7430 | Values: 118.0 125.0 122.0 123.0 121.0 129.0 123.0 124.0 116.0 117.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.70 Median:150.00 stddev:5.96 (4.0%) stddev-sans-first:6.17 16:26:08 INFO - PROCESS | 7430 | Values: 145.0 153.0 149.0 150.0 150.0 139.0 150.0 160.0 150.0 141.0 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - PROCESS | 7430 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.65 (2.4%) stddev-sans-first:1.74 16:26:08 INFO - PROCESS | 7430 | Values: 68.0 68.0 69.0 67.0 70.0 68.0 68.0 64.0 67.0 66.0 16:26:08 INFO - PROCESS | 7430 | -------- Summary: end -------- 16:26:08 INFO - PROCESS | 7430 | 16:26:08 INFO - TEST-INFO | 7430: exit 0 16:26:09 INFO - TEST-OK | kraken | took 59016ms 16:26:09 INFO - TEST-START | v8_7 16:26:09 INFO - operating with platform_type : linux_ 16:26:09 INFO - Initialising browser for v8_7 test... 16:26:09 INFO - TEST-INFO | started process 7680 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpN07HIm/profile http://localhost:38694/getInfo.html) 16:26:12 INFO - PROCESS | 7680 | __metrics Screen width/height:1600/1200 16:26:12 INFO - PROCESS | 7680 | colorDepth:24 16:26:12 INFO - PROCESS | 7680 | Browser inner width/height: 1024/697 16:26:12 INFO - PROCESS | 7680 | __metrics 16:26:13 INFO - PROCESS | 7680 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:26:13 INFO - PROCESS | 7680 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:26:14 INFO - TEST-INFO | 7680: exit 0 16:26:14 INFO - Browser initialized. 16:26:14 INFO - Running cycle 1/1 for v8_7 test... 16:26:14 INFO - TEST-INFO | started process 7765 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpN07HIm/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:26:26 INFO - PROCESS | 7765 | RSS: Main: 151855104 16:26:26 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | Cycle 1(1): loaded http://localhost:38694/tests/v8_7/run.html (next: http://localhost:38694/tests/v8_7/run.html) 16:26:47 INFO - PROCESS | 7765 | RSS: Main: 586612736 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | __start_tp_report 16:26:47 INFO - PROCESS | 7765 | _x_x_mozilla_page_load 16:26:47 INFO - PROCESS | 7765 | _x_x_mozilla_page_load_details 16:26:47 INFO - PROCESS | 7765 | |i|pagename|runs| 16:26:47 INFO - PROCESS | 7765 | |0;Richards;168.4352366515075 16:26:47 INFO - PROCESS | 7765 | |1;DeltaBlue;192.04916458613405 16:26:47 INFO - PROCESS | 7765 | |2;Encrypt;281.37310073157005 16:26:47 INFO - PROCESS | 7765 | |3;Decrypt;5268.421052631579 16:26:47 INFO - PROCESS | 7765 | |4;RayTrace;1990.0596421471173 16:26:47 INFO - PROCESS | 7765 | |5;Earley;796.9745222929936 16:26:47 INFO - PROCESS | 7765 | |6;Boyer;12048.192771084337 16:26:47 INFO - PROCESS | 7765 | |7;RegExp;26526.315789473683 16:26:47 INFO - PROCESS | 7765 | |8;Splay;587.1990604815032 16:26:47 INFO - PROCESS | 7765 | |9;NavierStokes;4835.748792270531 16:26:47 INFO - PROCESS | 7765 | __end_tp_report 16:26:47 INFO - PROCESS | 7765 | __startTimestamp1450916807860__endTimestamp 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | ------- Summary: start ------- 16:26:47 INFO - PROCESS | 7765 | Number of tests: 10 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#0] Richards Cycles:1 Average:168.44 Median:168.44 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 168.4 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#1] DeltaBlue Cycles:1 Average:192.05 Median:192.05 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 192.0 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#2] Encrypt Cycles:1 Average:281.37 Median:281.37 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 281.4 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#3] Decrypt Cycles:1 Average:5268.42 Median:5268.42 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 5268.4 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#4] RayTrace Cycles:1 Average:1990.06 Median:1990.06 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 1990.1 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#5] Earley Cycles:1 Average:796.97 Median:796.97 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 797.0 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#6] Boyer Cycles:1 Average:12048.19 Median:12048.19 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 12048.2 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#7] RegExp Cycles:1 Average:26526.32 Median:26526.32 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 26526.3 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#8] Splay Cycles:1 Average:587.20 Median:587.20 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 587.2 16:26:47 INFO - PROCESS | 7765 | 16:26:47 INFO - PROCESS | 7765 | [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 16:26:47 INFO - PROCESS | 7765 | Values: 4835.7 16:26:47 INFO - PROCESS | 7765 | -------- Summary: end -------- 16:26:47 INFO - PROCESS | 7765 | 16:26:48 INFO - TEST-INFO | 7765: exit 0 16:26:48 INFO - TEST-OK | v8_7 | took 39719ms 16:26:48 INFO - SUITE-END | took 1211s 16:26:48 INFO - Completed test suite (00:20:11) 16:26:48 INFO - Working with test: dromaeo_css 16:26:48 INFO - Generating results file: dromaeo_css 16:26:48 INFO - Working with test: kraken 16:26:48 INFO - Generating results file: kraken 16:26:48 INFO - javascript benchmark 16:26:48 INFO - Working with test: v8_7 16:26:48 INFO - Generating results file: v8_7 16:26:48 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:26:48 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 16:26:48 INFO - process_Request line: dromaeo_css 6784.41 graph.html#tests=[[72,63,35]] 16:26:48 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:26:48 INFO - process_Request line: kraken 1501.40 graph.html#tests=[[232,63,35]] 16:26:48 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:26:49 INFO - process_Request line: v8_7 19747.69 graph.html#tests=[[230,63,35]] 16:26:49 INFO - RETURN: dromaeo_css: 6784.41 16:26:49 INFO - RETURN: kraken: 1501.40 16:26:49 INFO - RETURN: v8_7: 19747.69 16:26:49 INFO - javascript benchmark 16:26:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3101.286704058067, 3171.5242713592975, 3218.147190994387, 3220.855061522231, 3253.2517520970177, 5063.797448102081, 5134.178531434971, 5266.183741520061, 5268.104422248218, 5284.260203571494, 5198.4801519848, 5242.501962313584, 5269.76286067127, 5295.7881684732565, 5298.841034768953, 4728.598069164125, 4781.258904869751, 4781.402324709411, 4872.4640289568115, 4873.902521949559, 4814.8314808981695, 4827.300041493982, 4889.535494128052, 4896.0, 4903.190973489388, 4717.221658426355, 4721.480637129913, 4806.87982800431, 4815.15734746418, 4830.855074347775, 4784.856454306377, 4797.18447863864, 4800.495947925488, 4807.78364973577, 4855.902881942377, 4692.6245900327895, 4777.737224452654, 4791.664583479158, 4810.39870016248, 4831.396075490564, 5009.674371165899, 5072.0616685912855, 5088.7964481420695, 5094.490550944913, 5119.38567371914, 5637.520810731052, 5642.520385767215, 5645.717714114319, 5648.209250704905, 5651.660900345993, 5521.806736764234, 5633.80281690143, 5653.4911857932575, 5678.488935995739, 5688.772449102031, 5247.68513889164, 5255.894882102375, 5296.92054619181, 5298.231756395301, 5324.680519168863, 5674.347450043216, 5687.60186786921, 5694.430556944272, 5695.572832037614, 5699.316082070095, 5393.352797664227, 5473.343198816088, 5501.394846566875, 5502.889942201214, 5503.394626591072, 5544.306961629796, 5565.721713914288, 5566.944330556724, 5582.581306402036, 5628.0, 5603.523700485504, 5607.971960140173, 5613.522850557667, 5614.494695477427, 5620.437956204347, 5513.972430137904, 5564.610477266552, 5623.915641265303, 5626.718664066781, 5631.80288689902, 4364.061726728705, 4364.716293440916, 4388.868333949987, 4408.735475871458, 4422.027154026109, 3869.5163104611925, 3875.670568001695, 3883.0292426893275, 3885.261800257942, 3923.3722604383734, 5176.042432150064, 5190.6107041972, 5191.428942816287, 5238.214267859851, 5265.868353291122, 5521.144222645416, 5654.717264136777, 5732.484076433141, 5744.425557444222, 5778.566607504454, 5206.55744261742, 5206.661566998208, 5206.71363075027, 5216.686998780009, 5246.265522826808, 4872.098661747517, 4881.975590122027, 4882.365292512021, 4883.731394773251, 4922.286268491121, 5666.065099258576, 5738.053221218453, 5755.539556835443, 5794.333651630032, 5902.616329938626, 5653.519450846724, 5658.5190258826815, 5686.886262274814, 5687.203791469115, 5692.715364231854, 5777.0, 5823.242978412949, 5833.883322333614, 5835.533157347487, 5836.737346819318, 5355.732213389393, 5450.1007333789485, 5451.48210919962, 5481.643693159932, 5580.665160090245, 5669.603127781016, 5683.033884239772, 5698.287714035746, 5702.6293290935955, 5705.058665320175, 5536.806211782568, 5557.388687244319, 5558.471945165203, 5558.583106266965, 5568.582356323373, 5394.433584473731, 5501.0, 5512.614117011771, 5513.779448822002, 5659.886802264014, 5426.972865135649, 5427.782888684408, 5428.83713488596, 5430.782768689208, 5434.809781657623, 5525.557955363481, 5539.58453116026, 5550.639208451437, 5565.026120428828, 5615.326160860723, 5336.0395128875625, 5396.784128634968, 5427.511523962862, 5451.100568406168, 5542.501174894279], "unit": "score", "name": "dojo.html", "value": 5191.275687218115}, {"lowerIsBetter": false, "replicates": [559.4852735483355, 567.3078843810549, 586.7271718650829, 606.2573347649129, 616.045130048425, 27357.86321068397, 28075.719242807565, 35114.29771404574, 35389.0, 35415.0, 39054.609453905454, 39418.80290598543, 39448.80275598625, 39527.0, 39544.0, 7362.852742945138, 8262.917370826304, 8493.98072231333, 8494.40539162259, 8497.150284971514, 7380.557166569996, 9947.801043979116, 10068.194544436428, 10090.142337901287, 10145.137663298628, 9941.552630131662, 10022.54898529568, 10037.247206459508, 10037.899621003806, 10041.096301332878, 7383.889241661379, 7397.075365579302, 9893.070851184022, 9962.601495940155, 10003.549840257207, 9779.46212958287, 9827.754306142333, 9837.655682051094, 9894.158996485306, 9920.0, 35819.4627080594, 36347.63652363469, 36354.09114772126, 36362.272754545025, 36398.454023189675, 3273.8035717857006, 3293.8517766700315, 3365.562476878015, 3366.848491817874, 3396.0660818275173, 27125.1862444127, 29516.114516564536, 29573.408531829456, 29677.258068548243, 30584.54123188154, 3863.613638636114, 3881.4639176284713, 3937.3503371943593, 3961.861334853295, 4049.2913740095364, 262024.0, 262091.0, 263524.6823765888, 263584.0, 263607.0, 47775.76112119487, 48326.75836620794, 54811.72594137004, 55153.72423137858, 55189.44810551843, 50785.49214507882, 50965.49034509682, 51188.48811511837, 51235.487645123816, 51298.0, 13071.607851764462, 13212.33938303081, 13227.669308267177, 13229.933850330686, 13239.801402978963, 9641.662541811143, 9659.082387173208, 9661.806763864824, 9674.467904265333, 9680.0, 17818.821811781974, 18319.267229310946, 18513.166907489194, 18568.628627427377, 18583.53541161482, 7220.566765994057, 7421.406287496988, 7467.327940485382, 7476.663550140148, 7507.436942229246, 944.3106532231509, 951.2057432044182, 970.5680971967407, 1015.1067060986285, 1057.5981127171779, 28373.858130709214, 28483.43033139367, 28569.57145642817, 28589.85705071503, 28796.0, 998.4209158687944, 1015.1726343030407, 1055.894410558938, 1107.5514416661265, 1125.6904351303294, 13192.670183245496, 13238.867611323763, 13417.597472075853, 13495.25776082325, 13500.257485838372, 267328.0, 270003.0, 270978.0, 271028.0, 271130.0, 46092.3086153708, 46291.76854115708, 46311.76844115825, 46316.0, 50420.24369634459, 1325.3042152869627, 1327.462377737018, 1337.6522104252965, 1349.8785109340206, 1352.4657760184673, 23433.41416464602, 24149.034038638256, 26020.479590408464, 29096.854515728133, 30025.249368765955, 43764.0, 44114.55885441233, 44233.778831105636, 44268.335974961046, 44284.77857610691, 241951.0, 243736.0, 243775.0, 244240.0, 282372.0, 24965.251042468753, 29099.563506547842, 29293.70706292995, 29513.557296640138, 29602.259943501584, 34292.65707342895, 38226.426603601525, 39262.0, 39272.41091383689, 39302.80348598239, 32303.70785168661, 33132.0, 33428.0, 33656.83171584126, 33737.83131084329, 33782.0, 34531.4820277691, 35759.106022349646, 35776.28447431089, 36104.63895361118, 31891.840540797148, 32453.67546324601, 32464.188395290308, 32520.67479325271, 32554.83722581372, 29923.70076299209, 30856.228594285323, 32604.184895377806, 32648.510272346408, 32742.181445464055, 22629.886850565643, 22773.202937897095, 22920.885395572914, 24958.875205623855, 24966.750332496442], "unit": "score", "name": "ext.html", "value": 17485.698388741173}, {"lowerIsBetter": false, "replicates": [355.21320275589596, 355.82208895552225, 356.8768774772704, 360.14106356340136, 362.08934529657904, 14119.858801411983, 14412.207328596924, 15969.0, 16270.918645406771, 17097.31610735569, 15217.315220815062, 15229.771553426708, 15475.922620386882, 15478.5356439307, 15672.921635391807, 5153.123968925292, 5429.592780541456, 5430.0768869292315, 5430.348358197002, 5434.91847622286, 3998.56015838259, 4075.1034772350035, 5274.709890956015, 5784.595078344518, 6009.1587177795145, 5864.413558644144, 5937.88124237515, 5942.821715348547, 5961.076033214858, 5972.850678733044, 5758.654480731169, 5890.499307558863, 5892.322382925976, 5893.793717219876, 5898.262717160355, 5218.739063046833, 5219.269302297644, 5223.764930578133, 5225.6603320784025, 5228.398734145584, 18926.62146757071, 19166.329178478827, 19341.51646208856, 20267.29064482736, 20329.898350508152, 2078.293380250707, 2137.1451419432196, 2178.2920550821045, 2203.9779602203935, 2252.4931890324547, 9366.250699943988, 9416.387934784218, 9495.952520237355, 9652.324337296392, 9701.417914925127, 1803.0984507746127, 1830.478313680608, 1850.9814901850943, 1855.415544103603, 1867.6264747050643, 59223.40776592222, 62039.689801551154, 62473.0, 62570.68714656398, 62599.37400626027, 9447.244220462346, 9483.241340692866, 9502.809943801085, 9509.857352139723, 9540.427574345422, 12034.338111403957, 12065.095117866194, 12138.696532586579, 12140.69648258783, 12166.0, 6675.732970681215, 6713.12729345182, 6716.227633822173, 6742.797716068526, 6754.6284954327, 6151.7539298428455, 6201.596896201732, 6203.100550420254, 6206.41736357226, 6345.397187267295, 3707.925841483155, 3792.715546334036, 3914.9804250978564, 3950.7827069510877, 3957.9010524736536, 1601.3274424741637, 1629.4052670775081, 1655.5364497940595, 1662.2602941690832, 1709.2821015173408, 915.514777168102, 928.5403725155955, 950.9995962737574, 1004.0160642570322, 1032.7624646331385, 16297.103659298853, 16833.15834208309, 17231.0, 17311.220995055002, 17407.91296043512, 922.7600823785864, 933.5425641435784, 939.3612343606277, 941.3739862991171, 951.70497145885, 9521.857172142423, 9635.13283804461, 9663.178629816544, 9698.224142068617, 9754.853677194847, 391868.0, 394418.0, 396312.0, 510153.0, 520594.0, 76757.61621191859, 79115.0, 80992.59503702562, 82872.58563707264, 83201.58399207966, 1162.5524173193382, 1163.3020187887369, 1205.8311836342918, 1223.9596343108465, 1236.3632729144497, 15616.0, 15772.526824195293, 15903.125328106602, 15974.760378594563, 16035.438759643357, 34183.31633367368, 34216.486752699224, 34603.78886738952, 35183.64816351906, 35986.28027439489, 45664.31503527383, 45819.0, 46411.0, 46652.53347466482, 48186.03627927492, 9598.472084035446, 9683.41899486033, 9686.951565242129, 9810.411375317504, 9836.852447213441, 12514.374281285716, 13785.0, 16508.42220522276, 17597.736033959245, 17784.644307114046, 13474.93262533681, 14079.648008799863, 14176.716465670836, 14509.129452232899, 14675.853241467876, 13831.446742130605, 14051.859481405056, 14146.080504767157, 14555.490557830424, 14621.0, 11407.0, 11525.135614828754, 14178.361973711415, 14327.283635818376, 14376.928115359357, 8966.551672416483, 9049.683261085831, 9092.408993415407, 9111.817763644822, 9133.954330228573, 14034.228117453638, 14049.776007964161, 14766.63083422923, 14849.554513364616, 15239.161846098128], "unit": "score", "name": "jquery.html", "value": 8643.965208322177}, {"lowerIsBetter": false, "replicates": [1336.8195293635356, 1420.1550077703769, 1428.6928310413261, 1559.2515592515592, 1734.9999999999993, 6609.173853268341, 7098.148222213335, 7184.461165412596, 7195.928040719591, 7208.098987626547, 7151.964240178791, 7308.59802710852, 7313.305236002585, 7314.707411703539, 7333.926660733391, 2168.0568952505673, 2193.4077798994263, 2197.9780202198012, 2198.1866709317574, 2198.8790616516085, 2127.117246342766, 2183.1049269799387, 2195.2755590655047, 2202.8237740980685, 2206.8013878750908, 2173.0655817998254, 2200.19692812124, 2216.5345277491747, 2236.2508559632543, 2238.9888050559725, 2178.6405243134864, 2199.120351859253, 2201.8348623853194, 2202.1301585873653, 2202.757696653367, 2159.470929622237, 2193.692882996366, 2210.4031911383836, 2211.1155537784853, 2215.711957445538, 7341.5962122083265, 7427.851442971164, 7431.368333691643, 7434.256574342577, 7450.552966822008, 4197.412362269254, 4244.893877653052, 4265.786710664454, 4284.3145096784365, 4291.0, 2968.5250359942306, 2988.073697153889, 3005.3688725367697, 3012.774041946841, 3031.1815809731247, 1089.8201796703613, 1093.9343639381582, 1105.9447027648666, 1115.654147214366, 1128.3117298448021, 9790.363626364264, 9798.461084640341, 9819.263555233314, 9853.162481189107, 9904.653337133166, 3938.428927805452, 3965.940510892339, 3996.820143093568, 4021.7586944783407, 4026.1947610477437, 8603.182697643713, 8636.78408039804, 8644.265237454762, 8652.697155599524, 8653.307735381155, 4353.238183317907, 4386.122775444924, 4387.3857659927635, 4387.956120438755, 4396.318570621559, 3589.3718599245026, 3697.2790305890094, 3903.1998440319667, 3993.940090898639, 4014.6386825185873, 3388.9661103389144, 3418.435958066891, 3419.9316013679586, 3448.4654878493875, 3450.654934506529, 1087.1085709718113, 1092.63396762084, 1099.8735145458215, 1102.73534351755, 1104.5857803323754, 1271.5486002469174, 1276.3554405025588, 1280.4494067550856, 1282.9679258018625, 1292.2182079841562, 6897.0, 6921.134858142732, 7000.47496437759, 7006.054182685374, 7027.8594428111155, 1070.8607880975578, 1091.6179337232063, 1098.549594666199, 1100.7028102412303, 1131.932084074958, 5301.310829592206, 5342.2787923630585, 5344.706041167696, 5346.786128554814, 5348.3849357323625, 8469.661213551512, 8522.70170544028, 8599.269062129664, 8669.393142480092, 8788.0, 8592.570371481526, 8644.913550864536, 8667.956660216785, 8685.088065753005, 8687.609057592423, 6880.208775990825, 7032.92967070333, 7057.941308803721, 7110.3600675939415, 7117.501774875813, 6791.422729068085, 6816.693248803716, 6820.863582728417, 6822.692978816064, 6827.863442731217, 7571.091469023752, 7725.5364678119495, 7922.564258965582, 7966.16355282687, 8017.799555010938, 8053.073896501743, 8171.346292296721, 8204.712835050745, 8320.251177394064, 8350.248477637042, 5569.777208911598, 5642.435756424325, 6172.197614310111, 6318.431341179132, 6351.301356850835, 6997.930020699728, 7013.824654383681, 7014.68433920485, 7041.155061392666, 7047.1895731989425, 6383.202099737533, 6452.419282264619, 6522.858499762618, 6544.9672751635935, 6627.304133066152, 6323.304436511888, 6377.4579160771855, 6450.48396128318, 6598.604083754798, 6667.066610674607, 6563.113979612882, 6604.63674497888, 6609.3060228675295, 6609.405153536205, 6610.6694665267505, 5574.637648552831, 5606.803761868315, 5633.267675202197, 5656.23640808502, 5667.489925906688, 6135.049067394561, 6165.075238714228, 6167.074938759222, 6172.259328880563, 6176.413240742122], "unit": "score", "name": "mootools.html", "value": 4254.671086085922}, {"lowerIsBetter": false, "replicates": [322.40033537620025, 330.86930662388374, 332.3502552509843, 332.9833508324583, 338.3740080850427, 5899.498542623876, 5933.288005439343, 5958.255218097738, 5978.222831031965, 6026.487748541379, 5861.150133230679, 5873.823785286438, 5876.8236952891375, 5881.264841894763, 5882.88234235315, 4563.931541026887, 4575.656825738066, 4579.175748365309, 4581.587657110876, 4587.8623641290815, 4485.5962963333295, 4555.7949892254765, 4560.97719511402, 4574.176648203322, 4577.473590537081, 4515.367848501212, 4522.886927826814, 4524.298733696266, 4526.185286648402, 4529.207388706979, 4325.5025672047805, 4503.369528266046, 4531.682782205247, 4533.455985281755, 4538.954610453903, 3871.4192871069286, 3910.120222949842, 3914.823832927525, 3921.941170882439, 3926.3325234709882, 5555.0, 5571.387147413781, 5573.777048918038, 5582.8325150245555, 5590.133529302964, 4982.975085124587, 5076.873078173038, 5100.974495127538, 5143.691378517301, 5184.533391994739, 6030.6080104793045, 6080.270367555878, 6144.631322120687, 6152.846178845564, 6218.751249949996, 4338.175746608134, 4343.153085148398, 4346.217680817452, 4354.673399495019, 4378.474583050022, 6319.146915166486, 6486.091947127406, 6496.123023391829, 6498.0, 6509.381608747209, 4478.014836735978, 4512.300593408027, 4516.254817955, 4517.503074661785, 4518.570735780096, 4571.2000399929875, 4571.451425828922, 4577.633789296849, 4579.885502862388, 4581.358609794632, 5238.633295669343, 5256.106461901486, 5294.947050529446, 5295.602829787781, 5309.548688361532, 6149.0776383542825, 6302.401271879165, 6303.46420554258, 6306.779262725782, 6316.021016742412, 1612.7258366077795, 1625.9756103658456, 1626.056887005534, 1626.9993953718615, 1627.9755803662954, 1075.3010543147018, 1075.725689949058, 1085.1264731890747, 1095.2990086344746, 1129.3054771315747, 5924.940750592439, 5932.2584676915385, 5936.347001829795, 5939.970300148471, 5966.985612445895, 6426.164598602152, 6487.610743355413, 6489.091527186198, 6497.610143391412, 6498.187726534184, 5896.823095307148, 5965.045592705146, 5966.075258334965, 5968.045112781934, 5970.611910225908, 3440.776349537322, 3522.4188008978745, 3522.735794815399, 3525.242072954276, 3527.0476971217627, 6652.268250492442, 6747.089142965735, 6769.492288078413, 6771.28901465339, 6784.7964561063245, 6403.775867844603, 6637.834054148685, 6641.900371494431, 6643.435307998778, 6651.600903945779, 6237.781677641261, 6594.802155935329, 6688.3646053624825, 6717.932820671731, 6718.664066796739, 6488.091667166506, 6561.770338038336, 6571.310012448624, 6580.276169621434, 6582.934170658232, 6504.479641628753, 6509.251436084722, 6522.902156467562, 6525.0538671893255, 6589.341065893373, 6684.565503242274, 6764.0868482754195, 6780.69486873082, 6785.08401365829, 6796.728130874907, 4470.709403888737, 4504.572065653758, 4508.391367165391, 4508.684392092522, 4509.90980180388, 6560.343965603402, 6564.6389448578875, 6581.0, 6583.111279977334, 6585.1439312889015, 6274.592151510137, 6388.87222255543, 6394.680265986589, 6395.520335974914, 6404.679766011774, 6376.489880809617, 6376.872462550816, 6381.680915954091, 6384.712687928962, 6386.042093685798, 6251.78118765841, 6252.218472690914, 6252.374762523711, 6252.468540174136, 6254.405831446005, 6206.751729930752, 6364.586301890362, 6378.649174295459, 6383.2978372378075, 6415.9358406417205, 6230.283517395557, 6240.159153049952, 6246.5002799775, 6249.125122482947, 6252.874942501034], "unit": "score", "name": "prototype.html", "value": 4751.551137546044}, {"lowerIsBetter": false, "replicates": [831.6922738586724, 843.1105184030845, 844.2401838345488, 849.4605925237477, 851.1998721202069, 13108.606741797737, 13340.332983350818, 13341.399637016333, 13361.398737056832, 13362.13146145501, 12142.514299428012, 12823.358832058406, 12824.230546167213, 12827.615171544845, 12836.935815320934, 652.7030201258416, 670.0217682184017, 769.3614300130906, 790.044046703488, 804.3806269172734, 791.5844181804541, 795.6021989005498, 801.3148757812074, 802.3180296747747, 802.4904185841974, 826.2811354121923, 833.204289903141, 833.6040380819093, 834.1366685480529, 834.7829564313292, 682.7200847652464, 697.9755708550201, 819.8934138561979, 830.4104086098877, 833.7707130539151, 611.4680228201448, 612.6416046612737, 612.6783438694677, 613.0773186354553, 613.0803794308537, 13230.73538529224, 13231.206127632373, 13313.467461301536, 13349.532766353132, 13363.532276370377, 11834.940825295818, 11853.407329633485, 12019.0, 12068.758624827542, 12239.755204895851, 10181.643642472553, 10318.226133039976, 10332.173426125966, 10393.740156496073, 10545.0, 3630.7640003399692, 3666.9633303666888, 3696.8890933272046, 3709.647583479565, 3806.029462487048, 46377.768111159225, 46442.76778616085, 46855.53144468546, 46948.29577556339, 46966.29550556745, 9381.530923453936, 9427.151556359824, 9441.291903107158, 9446.669366572138, 9545.90454095464, 15857.841421585867, 15860.127692976766, 15885.761713574075, 15895.841041589436, 15905.840941590435, 7222.891656625155, 7271.563706177646, 7409.222031686703, 7459.962700186465, 7572.659230334648, 4378.4745830499905, 4410.3825464435, 4498.775061246924, 4567.817287308537, 4620.0, 3010.1270631516613, 3031.621047369079, 3041.5893854329825, 3045.0408121441674, 3048.801827881225, 548.958828087895, 564.2241917363625, 576.3544829790661, 592.943670351316, 615.3815415507387, 598.141666708275, 615.602936106209, 625.5402279324693, 660.5112216959511, 677.6916502991127, 8455.492670439793, 8535.658573656985, 8679.17547832955, 8724.34567407447, 8859.335549833788, 515.9767810448539, 563.8308507447831, 611.2817439508559, 652.2909975863269, 653.3825534869503, 3767.2277183177384, 4244.808983595745, 4251.893702657459, 4254.276772948606, 4327.935080973788, 52031.0, 52424.73787631038, 52605.7369713149, 52686.209706854424, 52701.472985269655, 45573.5442645576, 46470.0, 47168.29247561289, 49155.50844491581, 49434.752826235635, 42279.365809512885, 43888.0, 44092.3386149208, 44194.77902610466, 44474.55525444769, 8604.311655067704, 8900.0, 8906.821863562822, 9024.142706442877, 9062.728118156467, 33018.66981330156, 33020.83489582537, 33618.663813361556, 33632.327353453285, 33647.831760841036, 41392.586074138875, 42141.367879482445, 42208.36687449629, 42826.357604635334, 42879.0, 9746.9025309746, 9917.752056198653, 10237.09680624842, 10947.726306842393, 11264.0425563828, 10773.868146954143, 11159.72100697489, 11496.712582185177, 11531.769364612828, 11999.940000299943, 11160.330380177214, 11525.366104864313, 11610.6516804496, 11671.533138674358, 11712.765744685228, 10761.784764304826, 10864.130869530261, 11097.223194376316, 11303.152263580429, 11492.253003554742, 11072.667819965414, 11167.106631469625, 11240.381779002233, 11246.66260012201, 11428.457298074445, 9176.71945001554, 9530.85703714431, 9557.713268601952, 9568.904310956801, 9938.055884690943, 8803.471791692518, 9635.036496350309, 9745.805083898424, 9822.705318840446, 9828.4102953823], "unit": "score", "name": "yui.html", "value": 6146.997649685225}], "name": "dromaeo_css", "value": 6784.412061841759}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 113.0, 106.0, 112.0, 112.0, 112.0, 101.0, 111.0, 111.0, 104.0], "unit": "score", "name": "ai-astar", "value": 109.3}, {"lowerIsBetter": true, "replicates": [149.0, 125.0, 149.0, 161.0, 123.0, 152.0, 162.0, 124.0, 161.0, 124.0], "unit": "score", "name": "audio-beat-detection", "value": 143.0}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 160.0, 161.0, 162.0, 162.0, 160.0, 160.0, 160.0, 159.0], "unit": "score", "name": "audio-dft", "value": 160.4}, {"lowerIsBetter": true, "replicates": [71.0, 73.0, 83.0, 90.0, 72.0, 72.0, 71.0, 106.0, 89.0, 72.0], "unit": "score", "name": "audio-fft", "value": 79.9}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 136.0, 97.0, 96.0, 96.0, 138.0, 95.0, 95.0, 139.0], "unit": "score", "name": "audio-oscillator", "value": 108.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 147.0, 145.0, 146.0, 146.0, 145.0, 149.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.1}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 97.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [58.0, 58.0, 59.0, 57.0, 58.0, 57.0, 58.0, 66.0, 55.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 58.3}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 67.0, 68.0, 69.0, 68.0, 68.0, 74.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.3}, {"lowerIsBetter": true, "replicates": [83.0, 85.0, 84.0, 92.0, 84.0, 84.0, 85.0, 85.0, 85.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.1}, {"lowerIsBetter": true, "replicates": [118.0, 125.0, 122.0, 123.0, 121.0, 129.0, 123.0, 124.0, 116.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.8}, {"lowerIsBetter": true, "replicates": [145.0, 153.0, 149.0, 150.0, 150.0, 139.0, 150.0, 160.0, 150.0, 141.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 148.7}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 67.0, 70.0, 68.0, 68.0, 64.0, 67.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.5}], "name": "kraken", "value": 1501.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.4352366515075], "unit": "score", "name": "Richards", "value": 209.587974}, {"lowerIsBetter": false, "replicates": [192.04916458613405], "unit": "score", "name": "DeltaBlue", "value": 344.276426}, {"lowerIsBetter": false, "replicates": [281.37310073157005], "unit": "score", "name": "Encrypt", "value": 946.0072739999998}, {"lowerIsBetter": false, "replicates": [5268.421052631579], "unit": "score", "name": "Decrypt", "value": 50.52386613386611}, {"lowerIsBetter": false, "replicates": [1990.0596421471173], "unit": "score", "name": "RayTrace", "value": 371.84262437562455}, {"lowerIsBetter": false, "replicates": [796.9745222929936], "unit": "score", "name": "Earley", "value": 836.2412867132866}, {"lowerIsBetter": false, "replicates": [12048.192771084337], "unit": "score", "name": "Boyer", "value": 55.31642899999996}, {"lowerIsBetter": false, "replicates": [26526.315789473683], "unit": "score", "name": "RegExp", "value": 34.34268849206351}, {"lowerIsBetter": false, "replicates": [587.1990604815032], "unit": "score", "name": "Splay", "value": 138.77917300000004}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 19747.685329814532}]} 16:26:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "19747.69"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1501.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6784.41"}}} 16:26:49 INFO - Return code: 0 16:26:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:26:49 ERROR - # TBPL SUCCESS # 16:26:49 INFO - Running post-action listener: _resource_record_post_action 16:26:49 INFO - Running post-run listener: _resource_record_post_run 16:26:50 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 483328; Write bytes: 381243392; Read time: 180; Write time: 396844 16:26:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 74866688; Read time: 0; Write time: 64104 16:26:50 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 483328; Write bytes: 306376704; Read time: 180; Write time: 332740 16:26:50 INFO - Running post-run listener: _upload_blobber_files 16:26:50 INFO - Blob upload gear active. 16:26:50 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 16:26:50 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:26:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 16:26:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Mozilla-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 16:26:50 INFO - (blobuploader) - INFO - Open directory for files ... 16:26:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 16:26:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:26:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:51 INFO - (blobuploader) - INFO - Done attempting. 16:26:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:26:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:26:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:26:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:26:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:26:52 INFO - (blobuploader) - INFO - Done attempting. 16:26:52 INFO - (blobuploader) - INFO - Iteration through files over. 16:26:52 INFO - Return code: 0 16:26:52 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 16:26:52 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 16:26:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/128755376a34d59fbfd640ea99c830e06c92a53839df890fff80040e82a178b89b53aa37a8fa03a4ce075b8f811a461f2960bfde36c55a547d5414d579aae864", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c047f6634b2f76fadc0d2e52d5d43aebaf4b56da9e782d32d7611dd082ceaff6d22f5451bb362c7960fdf341f0b42cfe199d932f2f3d0d697b825627946ea2d2"} 16:26:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 16:26:52 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 16:26:52 INFO - Contents: 16:26:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/128755376a34d59fbfd640ea99c830e06c92a53839df890fff80040e82a178b89b53aa37a8fa03a4ce075b8f811a461f2960bfde36c55a547d5414d579aae864", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c047f6634b2f76fadc0d2e52d5d43aebaf4b56da9e782d32d7611dd082ceaff6d22f5451bb362c7960fdf341f0b42cfe199d932f2f3d0d697b825627946ea2d2"} 16:26:52 INFO - Copying logs to upload dir... 16:26:52 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1279.497052 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2015-12-23 16:26:52.271995) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-23 16:26:52.275462) ========= 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=004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/128755376a34d59fbfd640ea99c830e06c92a53839df890fff80040e82a178b89b53aa37a8fa03a4ce075b8f811a461f2960bfde36c55a547d5414d579aae864", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c047f6634b2f76fadc0d2e52d5d43aebaf4b56da9e782d32d7611dd082ceaff6d22f5451bb362c7960fdf341f0b42cfe199d932f2f3d0d697b825627946ea2d2"} build_url:https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/-y-e7qG4TWO8H3j4BNMEQQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/128755376a34d59fbfd640ea99c830e06c92a53839df890fff80040e82a178b89b53aa37a8fa03a4ce075b8f811a461f2960bfde36c55a547d5414d579aae864", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c047f6634b2f76fadc0d2e52d5d43aebaf4b56da9e782d32d7611dd082ceaff6d22f5451bb362c7960fdf341f0b42cfe199d932f2f3d0d697b825627946ea2d2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-23 16:26:52.301101) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:26:52.301518) ========= 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=004b26eee9c3b360c8654f40000001e1-1450914896.193129-1467025029 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004428 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-23 16:26:52.384291) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-23 16:26:52.384697) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-23 16:26:52.385065) ========= ========= Total master_lag: 0.17 =========