builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-004 starttime: 1486658963.51 results: success (0) revision: 1c204002b9bedaa9fb386dda2031e32d664d6871 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:23.514758) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:23.515313) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:23.515662) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:23.533019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:23.533445) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-09 08:49:23-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.9M=0s 2017-02-09 08:49:24 (42.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118427 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:23.668181) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:23.668699) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019163 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:23.719450) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 08:49:23.719817) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1c204002b9bedaa9fb386dda2031e32d664d6871 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1c204002b9bedaa9fb386dda2031e32d664d6871 --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-09 08:49:24,120 truncating revision to first 12 chars 2017-02-09 08:49:24,120 Setting DEBUG logging. 2017-02-09 08:49:24,120 attempt 1/10 2017-02-09 08:49:24,120 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1c204002b9be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-09 08:49:24,955 unpacking tar archive at: mozilla-aurora-1c204002b9be/testing/mozharness/ program finished with exit code 0 elapsedTime=1.242466 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-09 08:49:24.975403) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:24.975795) ========= script_repo_revision: 1c204002b9bedaa9fb386dda2031e32d664d6871 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:24.976222) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:24.976523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-09 08:49:24.985718) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-02-09 08:49:24.986064) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:49:25 INFO - MultiFileLogger online at 20170209 08:49:25 in /builds/slave/test 08:49:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:49:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:49:25 INFO - {'append_to_log': False, 08:49:25 INFO - 'base_work_dir': '/builds/slave/test', 08:49:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:49:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:49:25 INFO - 'branch': 'Mozilla-Aurora', 08:49:25 INFO - 'buildbot_json_path': 'buildprops.json', 08:49:25 INFO - 'config_files': ('talos/linux_config.py',), 08:49:25 INFO - 'default_actions': ('clobber', 08:49:25 INFO - 'read-buildbot-config', 08:49:25 INFO - 'download-and-extract', 08:49:25 INFO - 'populate-webroot', 08:49:25 INFO - 'create-virtualenv', 08:49:25 INFO - 'install', 08:49:25 INFO - 'run-tests'), 08:49:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:49:25 INFO - 'download_minidump_stackwalk': True, 08:49:25 INFO - 'download_symbols': 'ondemand', 08:49:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:49:25 INFO - 'tooltool.py': '/tools/tooltool.py', 08:49:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:49:25 INFO - '/tools/misc-python/virtualenv.py')}, 08:49:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:49:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:49:25 INFO - 'installer_path': 'installer.exe', 08:49:25 INFO - 'log_level': 'info', 08:49:25 INFO - 'log_name': 'talos', 08:49:25 INFO - 'log_to_console': True, 08:49:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:49:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:49:25 INFO - 'opt_config_files': (), 08:49:25 INFO - 'pip_index': False, 08:49:25 INFO - 'sps_profile': False, 08:49:25 INFO - 'sps_profile_interval': 0, 08:49:25 INFO - 'suite': 'dromaeojs', 08:49:25 INFO - 'system_bits': '32', 08:49:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:49:25 INFO - 'title': 'talos-linux64-ix-004', 08:49:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:49:25 INFO - 'use_talos_json': True, 08:49:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:49:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:49:25 INFO - 'work_dir': 'build'} 08:49:25 INFO - [mozharness: 2017-02-09 16:49:25.488468Z] Running clobber step. 08:49:25 INFO - Running pre-action listener: _resource_record_pre_action 08:49:25 INFO - Running main action method: clobber 08:49:25 INFO - rmtree: /builds/slave/test/build 08:49:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:49:25 INFO - Running post-action listener: _resource_record_post_action 08:49:25 INFO - [mozharness: 2017-02-09 16:49:25.948506Z] Finished clobber step (success) 08:49:25 INFO - [mozharness: 2017-02-09 16:49:25.948607Z] Running read-buildbot-config step. 08:49:25 INFO - Running pre-action listener: _resource_record_pre_action 08:49:25 INFO - Running main action method: read_buildbot_config 08:49:25 INFO - Using buildbot properties: 08:49:25 INFO - { 08:49:25 INFO - "project": "", 08:49:25 INFO - "product": "firefox", 08:49:25 INFO - "who": "ffxbld@noreply.mozilla.org", 08:49:25 INFO - "installer_path": "public/build/target.tar.bz2", 08:49:25 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:49:25 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 08:49:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:49:25 INFO - "stage_platform": "linux64-pgo", 08:49:25 INFO - "basedir": "/builds/slave/test", 08:49:25 INFO - "buildnumber": 1047, 08:49:25 INFO - "platform": "ubuntu64_hw", 08:49:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:49:25 INFO - "slavebuilddir": "test", 08:49:25 INFO - "taskId": "cYBZO0w1TfmPWnZ9ZiRLJg", 08:49:25 INFO - "branch": "mozilla-aurora", 08:49:25 INFO - "script_repo_revision": "production", 08:49:25 INFO - "revision": "1c204002b9bedaa9fb386dda2031e32d664d6871", 08:49:25 INFO - "slavename": "talos-linux64-ix-004", 08:49:25 INFO - "repo_path": "releases/mozilla-aurora" 08:49:25 INFO - } 08:49:25 INFO - Finding installer, test and symbols from parent task. 08:49:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cYBZO0w1TfmPWnZ9ZiRLJg'}, attempt #1 08:49:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cYBZO0w1TfmPWnZ9ZiRLJg'}, attempt #1 08:49:26 INFO - Task dependencies: bxXyMWRFQf-SndmpKA-mVQ 08:49:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ'}, attempt #1 08:49:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.tar.bz2 08:49:27 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/test_packages.json 08:49:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.crashreporter-symbols.zip 08:49:27 INFO - Running post-action listener: _resource_record_post_action 08:49:27 INFO - [mozharness: 2017-02-09 16:49:27.363011Z] Finished read-buildbot-config step (success) 08:49:27 INFO - [mozharness: 2017-02-09 16:49:27.363228Z] Running download-and-extract step. 08:49:27 INFO - Running pre-action listener: _resource_record_pre_action 08:49:27 INFO - Running main action method: download_and_extract 08:49:27 INFO - mkdir: /builds/slave/test/build/tests 08:49:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:27 INFO - trying https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.test_packages.json 08:49:27 INFO - Downloading https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:49:27 INFO - Downloaded 896 bytes. 08:49:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:49:27 INFO - Using the following test package requirements: 08:49:27 INFO - {u'common': [u'target.common.tests.zip'], 08:49:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:49:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:49:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:49:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:49:27 INFO - u'mozbase': [u'target.common.tests.zip'], 08:49:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:49:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:49:27 INFO - u'web-platform': [u'target.common.tests.zip', 08:49:27 INFO - u'target.web-platform.tests.zip'], 08:49:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:49:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:49:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.common.tests.zip 08:49:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:49:27 INFO - Fetch https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.common.tests.zip into memory 08:49:29 INFO - Expected file size: 21854920 08:49:29 INFO - Obtained file size: 21854920 08:49:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:49:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.common.tests.zip 08:49:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:49:33 INFO - Fetch https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.common.tests.zip into memory 08:49:34 INFO - Expected file size: 21854920 08:49:34 INFO - Obtained file size: 21854920 08:49:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.talos.tests.zip 08:49:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:49:38 INFO - Fetch https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.talos.tests.zip into memory 08:49:40 INFO - Expected file size: 14403485 08:49:40 INFO - Obtained file size: 14403485 08:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:40 INFO - trying https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.tar.bz2 08:49:40 INFO - Downloading https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:49:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:49:42 INFO - Downloaded 66068169 bytes. 08:49:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.tar.bz2 08:49:42 INFO - mkdir: /builds/slave/test/properties 08:49:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:49:42 INFO - Writing to file /builds/slave/test/properties/build_url 08:49:42 INFO - Contents: 08:49:42 INFO - build_url:https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.tar.bz2 08:49:42 INFO - Running post-action listener: _resource_record_post_action 08:49:42 INFO - Running post-action listener: set_extra_try_arguments 08:49:42 INFO - [mozharness: 2017-02-09 16:49:42.794813Z] Finished download-and-extract step (success) 08:49:42 INFO - [mozharness: 2017-02-09 16:49:42.794909Z] Running populate-webroot step. 08:49:42 INFO - Running pre-action listener: _resource_record_pre_action 08:49:42 INFO - Running main action method: populate_webroot 08:49:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:42 INFO - '--tppagecycles', 08:49:42 INFO - '7'], 08:49:42 INFO - 'tests': ['tsvgm']}}, 08:49:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:49:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:49:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:49:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:49:42 INFO - 'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:49:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:49:42 INFO - 'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['damp', 'tps']}, 08:49:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:49:42 INFO - 'tests': ['damp', 'tps']}, 08:49:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['dromaeo_dom']}, 08:49:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['basic_compositor_video']}, 08:49:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:49:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['a11yr', 08:49:42 INFO - 'ts_paint', 08:49:42 INFO - 'tpaint', 08:49:42 INFO - 'sessionrestore', 08:49:42 INFO - 'sessionrestore_no_auto_restore', 08:49:42 INFO - 'tabpaint']}, 08:49:42 INFO - 'other-e10s': {'tests': ['a11yr', 08:49:42 INFO - 'ts_paint', 08:49:42 INFO - 'tpaint', 08:49:42 INFO - 'sessionrestore', 08:49:42 INFO - 'sessionrestore_no_auto_restore', 08:49:42 INFO - 'tabpaint']}, 08:49:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:49:42 INFO - 'ts_paint', 08:49:42 INFO - 'tpaint', 08:49:42 INFO - 'sessionrestore', 08:49:42 INFO - 'sessionrestore_no_auto_restore', 08:49:42 INFO - 'tabpaint']}, 08:49:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:49:42 INFO - 'ts_paint', 08:49:42 INFO - 'tpaint', 08:49:42 INFO - 'sessionrestore', 08:49:42 INFO - 'sessionrestore_no_auto_restore', 08:49:42 INFO - 'tabpaint']}, 08:49:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['a11yr', 08:49:42 INFO - 'ts_paint', 08:49:42 INFO - 'tpaint', 08:49:42 INFO - 'sessionrestore', 08:49:42 INFO - 'sessionrestore_no_auto_restore', 08:49:42 INFO - 'tabpaint']}, 08:49:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['a11yr', 08:49:42 INFO - 'ts_paint', 08:49:42 INFO - 'tpaint', 08:49:42 INFO - 'sessionrestore', 08:49:42 INFO - 'sessionrestore_no_auto_restore', 08:49:42 INFO - 'tabpaint']}, 08:49:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['tsvgx', 08:49:42 INFO - 'tsvgr_opacity', 08:49:42 INFO - 'tart', 08:49:42 INFO - 'tscrollx', 08:49:42 INFO - 'cart', 08:49:42 INFO - 'tsvg_static']}, 08:49:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:49:42 INFO - 'tsvgr_opacity', 08:49:42 INFO - 'tart', 08:49:42 INFO - 'tscrollx', 08:49:42 INFO - 'cart', 08:49:42 INFO - 'tsvg_static']}, 08:49:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:49:42 INFO - 'talos_options': ['--disable-e10s'], 08:49:42 INFO - 'tests': ['tp5o']}, 08:49:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:49:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:49:42 INFO - 'talos_options': ['--disable-e10s', 08:49:42 INFO - '--xperf_path', 08:49:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:42 INFO - 'tests': ['tp5n']}, 08:49:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:49:42 INFO - 'talos_options': ['--xperf_path', 08:49:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:42 INFO - 'tests': ['tp5n']}}, 08:49:42 INFO - 'talos.zip': {'path': '', 08:49:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:42 INFO - Running post-action listener: _resource_record_post_action 08:49:42 INFO - [mozharness: 2017-02-09 16:49:42.807930Z] Finished populate-webroot step (success) 08:49:42 INFO - [mozharness: 2017-02-09 16:49:42.808020Z] Running create-virtualenv step. 08:49:42 INFO - Running pre-action listener: _resource_record_pre_action 08:49:42 INFO - Running main action method: create_virtualenv 08:49:42 INFO - Creating virtualenv /builds/slave/test/build/venv 08:49:42 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:49:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:49:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:42 INFO - Using real prefix '/usr' 08:49:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:49:43 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:45 INFO - Installing pip.................done. 08:49:45 INFO - Return code: 0 08:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:49:45 INFO - Reading from file tmpfile_stdout 08:49:45 INFO - Output received: 08:49:45 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:49:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:49:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:49:45 INFO - Using env: {'DISPLAY': ':0', 08:49:45 INFO - 'HOME': '/home/cltbld', 08:49:45 INFO - 'LANG': 'en_US.UTF-8', 08:49:45 INFO - 'LANGUAGE': 'en_US:en', 08:49:45 INFO - 'LOGNAME': 'cltbld', 08:49:45 INFO - 'MAIL': '/var/mail/cltbld', 08:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:45 INFO - 'MOZ_NO_REMOTE': '1', 08:49:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:45 INFO - 'NO_EM_RESTART': '1', 08:49:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:45 INFO - 'PWD': '/builds/slave/test', 08:49:45 INFO - 'SHELL': '/bin/bash', 08:49:45 INFO - 'SHLVL': '1', 08:49:45 INFO - 'TERM': 'linux', 08:49:45 INFO - 'TMOUT': '86400', 08:49:45 INFO - 'USER': 'cltbld', 08:49:45 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:45 INFO - '_': '/tools/buildbot/bin/python'} 08:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:45 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 08:49:45 INFO - Cleaning up... 08:49:45 INFO - Return code: 0 08:49:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:49:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:49:45 INFO - Using env: {'DISPLAY': ':0', 08:49:45 INFO - 'HOME': '/home/cltbld', 08:49:45 INFO - 'LANG': 'en_US.UTF-8', 08:49:45 INFO - 'LANGUAGE': 'en_US:en', 08:49:45 INFO - 'LOGNAME': 'cltbld', 08:49:45 INFO - 'MAIL': '/var/mail/cltbld', 08:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:45 INFO - 'MOZ_NO_REMOTE': '1', 08:49:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:45 INFO - 'NO_EM_RESTART': '1', 08:49:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:45 INFO - 'PWD': '/builds/slave/test', 08:49:45 INFO - 'SHELL': '/bin/bash', 08:49:45 INFO - 'SHLVL': '1', 08:49:45 INFO - 'TERM': 'linux', 08:49:45 INFO - 'TMOUT': '86400', 08:49:45 INFO - 'USER': 'cltbld', 08:49:45 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:45 INFO - '_': '/tools/buildbot/bin/python'} 08:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:46 INFO - Downloading/unpacking psutil>=3.1.1 08:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:48 INFO - Installing collected packages: psutil 08:49:48 INFO - Running setup.py install for psutil 08:49:48 INFO - building 'psutil._psutil_linux' extension 08:49:48 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 08:49:48 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 08:49:48 INFO - building 'psutil._psutil_posix' extension 08:49:48 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 08:49:48 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 08:49:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:48 INFO - Successfully installed psutil 08:49:48 INFO - Cleaning up... 08:49:48 INFO - Return code: 0 08:49:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:49:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:49:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:49:48 INFO - Using env: {'DISPLAY': ':0', 08:49:48 INFO - 'HOME': '/home/cltbld', 08:49:48 INFO - 'LANG': 'en_US.UTF-8', 08:49:48 INFO - 'LANGUAGE': 'en_US:en', 08:49:48 INFO - 'LOGNAME': 'cltbld', 08:49:48 INFO - 'MAIL': '/var/mail/cltbld', 08:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:48 INFO - 'MOZ_NO_REMOTE': '1', 08:49:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:48 INFO - 'NO_EM_RESTART': '1', 08:49:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:48 INFO - 'PWD': '/builds/slave/test', 08:49:48 INFO - 'SHELL': '/bin/bash', 08:49:48 INFO - 'SHLVL': '1', 08:49:48 INFO - 'TERM': 'linux', 08:49:48 INFO - 'TMOUT': '86400', 08:49:48 INFO - 'USER': 'cltbld', 08:49:48 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:48 INFO - '_': '/tools/buildbot/bin/python'} 08:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:51 INFO - Installing collected packages: mozsystemmonitor 08:49:51 INFO - Running setup.py install for mozsystemmonitor 08:49:51 INFO - Successfully installed mozsystemmonitor 08:49:51 INFO - Cleaning up... 08:49:51 INFO - Return code: 0 08:49:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:49:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:49:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:49:51 INFO - Using env: {'DISPLAY': ':0', 08:49:51 INFO - 'HOME': '/home/cltbld', 08:49:51 INFO - 'LANG': 'en_US.UTF-8', 08:49:51 INFO - 'LANGUAGE': 'en_US:en', 08:49:51 INFO - 'LOGNAME': 'cltbld', 08:49:51 INFO - 'MAIL': '/var/mail/cltbld', 08:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:51 INFO - 'MOZ_NO_REMOTE': '1', 08:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:51 INFO - 'NO_EM_RESTART': '1', 08:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:51 INFO - 'PWD': '/builds/slave/test', 08:49:51 INFO - 'SHELL': '/bin/bash', 08:49:51 INFO - 'SHLVL': '1', 08:49:51 INFO - 'TERM': 'linux', 08:49:51 INFO - 'TMOUT': '86400', 08:49:51 INFO - 'USER': 'cltbld', 08:49:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:51 INFO - '_': '/tools/buildbot/bin/python'} 08:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:51 INFO - Downloading/unpacking jsonschema==2.5.1 08:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:53 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:53 INFO - warning: no files found matching '*.txt' 08:49:53 INFO - no previously-included directories found matching 'build' 08:49:53 INFO - no previously-included directories found matching 'dist' 08:49:53 INFO - no previously-included directories found matching '.git*' 08:49:53 INFO - Installing collected packages: jsonschema, functools32 08:49:53 INFO - Running setup.py install for functools32 08:49:53 INFO - warning: no files found matching '*.txt' 08:49:53 INFO - no previously-included directories found matching 'build' 08:49:53 INFO - no previously-included directories found matching 'dist' 08:49:53 INFO - no previously-included directories found matching '.git*' 08:49:53 INFO - Successfully installed jsonschema functools32 08:49:53 INFO - Cleaning up... 08:49:53 INFO - Return code: 0 08:49:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:49:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:49:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:49:53 INFO - Using env: {'DISPLAY': ':0', 08:49:53 INFO - 'HOME': '/home/cltbld', 08:49:53 INFO - 'LANG': 'en_US.UTF-8', 08:49:53 INFO - 'LANGUAGE': 'en_US:en', 08:49:53 INFO - 'LOGNAME': 'cltbld', 08:49:53 INFO - 'MAIL': '/var/mail/cltbld', 08:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:53 INFO - 'MOZ_NO_REMOTE': '1', 08:49:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:53 INFO - 'NO_EM_RESTART': '1', 08:49:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:53 INFO - 'PWD': '/builds/slave/test', 08:49:53 INFO - 'SHELL': '/bin/bash', 08:49:53 INFO - 'SHLVL': '1', 08:49:53 INFO - 'TERM': 'linux', 08:49:53 INFO - 'TMOUT': '86400', 08:49:53 INFO - 'USER': 'cltbld', 08:49:53 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:53 INFO - '_': '/tools/buildbot/bin/python'} 08:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:54 INFO - Cleaning up... 08:49:54 INFO - Return code: 0 08:49:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:49:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:49:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:49:54 INFO - Using env: {'DISPLAY': ':0', 08:49:54 INFO - 'HOME': '/home/cltbld', 08:49:54 INFO - 'LANG': 'en_US.UTF-8', 08:49:54 INFO - 'LANGUAGE': 'en_US:en', 08:49:54 INFO - 'LOGNAME': 'cltbld', 08:49:54 INFO - 'MAIL': '/var/mail/cltbld', 08:49:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:54 INFO - 'MOZ_NO_REMOTE': '1', 08:49:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:54 INFO - 'NO_EM_RESTART': '1', 08:49:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:54 INFO - 'PWD': '/builds/slave/test', 08:49:54 INFO - 'SHELL': '/bin/bash', 08:49:54 INFO - 'SHLVL': '1', 08:49:54 INFO - 'TERM': 'linux', 08:49:54 INFO - 'TMOUT': '86400', 08:49:54 INFO - 'USER': 'cltbld', 08:49:54 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:49:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:54 INFO - '_': '/tools/buildbot/bin/python'} 08:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:54 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:56 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:57 INFO - Downloading docopt-0.6.1.tar.gz 08:49:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:57 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:57 INFO - Running setup.py install for blobuploader 08:49:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:57 INFO - Running setup.py install for requests 08:49:57 INFO - Running setup.py install for docopt 08:49:57 INFO - Successfully installed blobuploader requests docopt 08:49:57 INFO - Cleaning up... 08:49:57 INFO - Return code: 0 08:49:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:57 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:49:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 08:49:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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 08:49:57 INFO - Using env: {'DISPLAY': ':0', 08:49:57 INFO - 'HOME': '/home/cltbld', 08:49:57 INFO - 'LANG': 'en_US.UTF-8', 08:49:57 INFO - 'LANGUAGE': 'en_US:en', 08:49:57 INFO - 'LOGNAME': 'cltbld', 08:49:57 INFO - 'MAIL': '/var/mail/cltbld', 08:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:57 INFO - 'MOZ_NO_REMOTE': '1', 08:49:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:57 INFO - 'NO_EM_RESTART': '1', 08:49:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:57 INFO - 'PWD': '/builds/slave/test', 08:49:57 INFO - 'SHELL': '/bin/bash', 08:49:57 INFO - 'SHLVL': '1', 08:49:57 INFO - 'TERM': 'linux', 08:49:57 INFO - 'TMOUT': '86400', 08:49:57 INFO - 'USER': 'cltbld', 08:49:57 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:57 INFO - '_': '/tools/buildbot/bin/python'} 08:49:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:58 INFO - Running setup.py (path:/tmp/pip-Xs2fWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:58 INFO - Running setup.py (path:/tmp/pip-LwWiGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:58 INFO - Running setup.py (path:/tmp/pip-CABAaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:58 INFO - Running setup.py (path:/tmp/pip-n1KWg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:58 INFO - Running setup.py (path:/tmp/pip-A9J_z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:58 INFO - Running setup.py (path:/tmp/pip-SgSQPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:58 INFO - Running setup.py (path:/tmp/pip-AYQWSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:58 INFO - Running setup.py (path:/tmp/pip-NAk2rh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:58 INFO - Running setup.py (path:/tmp/pip-FnhATQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:58 INFO - Running setup.py (path:/tmp/pip-Gp6Yz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:58 INFO - Running setup.py (path:/tmp/pip-XF1Bos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:58 INFO - Running setup.py (path:/tmp/pip-BtZRNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:58 INFO - Running setup.py (path:/tmp/pip-D5BTAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:58 INFO - Running setup.py (path:/tmp/pip-gV8PFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:59 INFO - Running setup.py (path:/tmp/pip-UHqa7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:59 INFO - Running setup.py (path:/tmp/pip-XnTgvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:59 INFO - Running setup.py (path:/tmp/pip-roc275-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:59 INFO - Running setup.py install for manifestparser 08:49:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:59 INFO - Running setup.py install for mozcrash 08:49:59 INFO - Running setup.py install for mozdebug 08:49:59 INFO - Running setup.py install for mozdevice 08:49:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:59 INFO - Running setup.py install for mozfile 08:49:59 INFO - Running setup.py install for mozhttpd 08:49:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:59 INFO - Running setup.py install for mozinfo 08:49:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:59 INFO - Running setup.py install for mozInstall 08:50:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Running setup.py install for mozleak 08:50:00 INFO - Running setup.py install for mozlog 08:50:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Running setup.py install for moznetwork 08:50:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Running setup.py install for mozprocess 08:50:00 INFO - Running setup.py install for mozprofile 08:50:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Running setup.py install for mozrunner 08:50:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:50:00 INFO - Running setup.py install for mozscreenshot 08:50:00 INFO - Running setup.py install for moztest 08:50:00 INFO - Running setup.py install for mozversion 08:50:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:50:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:50:01 INFO - Cleaning up... 08:50:01 INFO - Return code: 0 08:50:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:01 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:50:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 08:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 08:50:01 INFO - Using env: {'DISPLAY': ':0', 08:50:01 INFO - 'HOME': '/home/cltbld', 08:50:01 INFO - 'LANG': 'en_US.UTF-8', 08:50:01 INFO - 'LANGUAGE': 'en_US:en', 08:50:01 INFO - 'LOGNAME': 'cltbld', 08:50:01 INFO - 'MAIL': '/var/mail/cltbld', 08:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:01 INFO - 'MOZ_NO_REMOTE': '1', 08:50:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:01 INFO - 'NO_EM_RESTART': '1', 08:50:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:01 INFO - 'PWD': '/builds/slave/test', 08:50:01 INFO - 'SHELL': '/bin/bash', 08:50:01 INFO - 'SHLVL': '1', 08:50:01 INFO - 'TERM': 'linux', 08:50:01 INFO - 'TMOUT': '86400', 08:50:01 INFO - 'USER': 'cltbld', 08:50:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:01 INFO - '_': '/tools/buildbot/bin/python'} 08:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:01 INFO - Running setup.py (path:/tmp/pip-guQklZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:01 INFO - Running setup.py (path:/tmp/pip-Jgw8YF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:01 INFO - Running setup.py (path:/tmp/pip-JhNpSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:01 INFO - Running setup.py (path:/tmp/pip-y8mtv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:01 INFO - Running setup.py (path:/tmp/pip-wSt0hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:01 INFO - Running setup.py (path:/tmp/pip-vbV_b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:01 INFO - Running setup.py (path:/tmp/pip-XRfCVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:01 INFO - Running setup.py (path:/tmp/pip-4kl7Di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:01 INFO - Running setup.py (path:/tmp/pip-L0wrRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:01 INFO - Running setup.py (path:/tmp/pip-OER22G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:02 INFO - Running setup.py (path:/tmp/pip-zjnP90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:02 INFO - Running setup.py (path:/tmp/pip-dAuEzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:02 INFO - Running setup.py (path:/tmp/pip-pu1U2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:02 INFO - Running setup.py (path:/tmp/pip-_oevWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:02 INFO - Running setup.py (path:/tmp/pip-ByXD9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:02 INFO - Running setup.py (path:/tmp/pip-_tSmiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:02 INFO - Running setup.py (path:/tmp/pip-WCWu2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:50:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:50:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:50: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 08:50: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 08:50: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 08:50: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 08:50: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 08:50: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 08:50:04 INFO - Downloading blessings-1.6.tar.gz 08:50:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:50:04 INFO - Installing collected packages: blessings 08:50:04 INFO - Running setup.py install for blessings 08:50:04 INFO - Successfully installed blessings 08:50:04 INFO - Cleaning up... 08:50:04 INFO - Return code: 0 08:50:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:50:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:50:04 INFO - Reading from file tmpfile_stdout 08:50:04 INFO - Current package versions: 08:50:04 INFO - argparse == 1.2.1 08:50:04 INFO - blessings == 1.6 08:50:04 INFO - blobuploader == 1.2.4 08:50:04 INFO - docopt == 0.6.1 08:50:04 INFO - functools32 == 3.2.3-2 08:50:04 INFO - jsonschema == 2.5.1 08:50:04 INFO - manifestparser == 1.1 08:50:04 INFO - mozInstall == 1.12 08:50:04 INFO - mozcrash == 1.0 08:50:04 INFO - mozdebug == 0.1 08:50:04 INFO - mozdevice == 0.49 08:50:04 INFO - mozfile == 1.2 08:50:04 INFO - mozhttpd == 0.7 08:50:04 INFO - mozinfo == 0.9 08:50:04 INFO - mozleak == 0.1 08:50:04 INFO - mozlog == 3.4 08:50:04 INFO - moznetwork == 0.27 08:50:04 INFO - mozprocess == 0.24 08:50:04 INFO - mozprofile == 0.28 08:50:04 INFO - mozrunner == 6.13 08:50:04 INFO - mozscreenshot == 0.1 08:50:04 INFO - mozsystemmonitor == 0.3 08:50:04 INFO - moztest == 0.8 08:50:04 INFO - mozversion == 1.4 08:50:04 INFO - psutil == 3.1.1 08:50:04 INFO - requests == 1.2.3 08:50:04 INFO - wsgiref == 0.1.2 08:50:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:50:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 08:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 08:50:05 INFO - Using env: {'DISPLAY': ':0', 08:50:05 INFO - 'HOME': '/home/cltbld', 08:50:05 INFO - 'LANG': 'en_US.UTF-8', 08:50:05 INFO - 'LANGUAGE': 'en_US:en', 08:50:05 INFO - 'LOGNAME': 'cltbld', 08:50:05 INFO - 'MAIL': '/var/mail/cltbld', 08:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:05 INFO - 'MOZ_NO_REMOTE': '1', 08:50:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:05 INFO - 'NO_EM_RESTART': '1', 08:50:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:05 INFO - 'PWD': '/builds/slave/test', 08:50:05 INFO - 'SHELL': '/bin/bash', 08:50:05 INFO - 'SHLVL': '1', 08:50:05 INFO - 'TERM': 'linux', 08:50:05 INFO - 'TMOUT': '86400', 08:50:05 INFO - 'USER': 'cltbld', 08:50:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:05 INFO - '_': '/tools/buildbot/bin/python'} 08:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:50:05 INFO - Cleaning up... 08:50:05 INFO - Return code: 0 08:50:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e7ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20e0630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 08:50:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:50:05 INFO - Using env: {'DISPLAY': ':0', 08:50:05 INFO - 'HOME': '/home/cltbld', 08:50:05 INFO - 'LANG': 'en_US.UTF-8', 08:50:05 INFO - 'LANGUAGE': 'en_US:en', 08:50:05 INFO - 'LOGNAME': 'cltbld', 08:50:05 INFO - 'MAIL': '/var/mail/cltbld', 08:50:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:05 INFO - 'MOZ_NO_REMOTE': '1', 08:50:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:05 INFO - 'NO_EM_RESTART': '1', 08:50:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:05 INFO - 'PWD': '/builds/slave/test', 08:50:05 INFO - 'SHELL': '/bin/bash', 08:50:05 INFO - 'SHLVL': '1', 08:50:05 INFO - 'TERM': 'linux', 08:50:05 INFO - 'TMOUT': '86400', 08:50:05 INFO - 'USER': 'cltbld', 08:50:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:50:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:05 INFO - '_': '/tools/buildbot/bin/python'} 08:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:50:05 INFO - Cleaning up... 08:50:05 INFO - Return code: 0 08:50:05 INFO - Running post-action listener: _resource_record_post_action 08:50:05 INFO - Running post-action listener: _start_resource_monitoring 08:50:05 INFO - Starting resource monitoring. 08:50:05 INFO - [mozharness: 2017-02-09 16:50:05.494225Z] Finished create-virtualenv step (success) 08:50:05 INFO - [mozharness: 2017-02-09 16:50:05.494726Z] Running install step. 08:50:05 INFO - Running pre-action listener: _resource_record_pre_action 08:50:05 INFO - Running main action method: install 08:50:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:50:05 INFO - Reading from file tmpfile_stdout 08:50:05 INFO - Detecting whether we're running mozinstall >=1.0... 08:50:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:50:05 INFO - Reading from file tmpfile_stdout 08:50:05 INFO - Output received: 08:50:05 INFO - Usage: mozinstall [options] installer 08:50:05 INFO - Options: 08:50:05 INFO - -h, --help show this help message and exit 08:50:05 INFO - -d DEST, --destination=DEST 08:50:05 INFO - Directory to install application into. [default: 08:50:05 INFO - "/builds/slave/test"] 08:50:05 INFO - --app=APP Application being installed. [default: firefox] 08:50:05 INFO - mkdir: /builds/slave/test/build/application 08:50:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:50:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:50:22 INFO - Reading from file tmpfile_stdout 08:50:22 INFO - Output received: 08:50:22 INFO - /builds/slave/test/build/application/firefox/firefox 08:50:22 INFO - Running post-action listener: _resource_record_post_action 08:50:22 INFO - [mozharness: 2017-02-09 16:50:22.333384Z] Finished install step (success) 08:50:22 INFO - [mozharness: 2017-02-09 16:50:22.333660Z] Running run-tests step. 08:50:22 INFO - Running pre-action listener: _resource_record_pre_action 08:50:22 INFO - Running main action method: run_tests 08:50:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:50:22 INFO - Python 2.7.3 08:50:22 INFO - Return code: 0 08:50:22 INFO - grabbing minidump binary from tooltool 08:50:22 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:22 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/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2169850>, '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 0x20de6d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20deb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:50:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:50:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:50:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:50:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:50:22 INFO - Return code: 0 08:50:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:50:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:50:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:50:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:50:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:50:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:50:22 INFO - Using env: {'DISPLAY': ':0', 08:50:22 INFO - 'HOME': '/home/cltbld', 08:50:22 INFO - 'LANG': 'en_US.UTF-8', 08:50:22 INFO - 'LANGUAGE': 'en_US:en', 08:50:22 INFO - 'LOGNAME': 'cltbld', 08:50:22 INFO - 'MAIL': '/var/mail/cltbld', 08:50:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:50:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:22 INFO - 'MOZ_NO_REMOTE': '1', 08:50:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:50:22 INFO - 'NO_EM_RESTART': '1', 08:50:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:22 INFO - 'PWD': '/builds/slave/test', 08:50:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:50:22 INFO - 'SHELL': '/bin/bash', 08:50:22 INFO - 'SHLVL': '1', 08:50:22 INFO - 'TERM': 'linux', 08:50:22 INFO - 'TMOUT': '86400', 08:50:22 INFO - 'USER': 'cltbld', 08:50:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371', 08:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:22 INFO - '_': '/tools/buildbot/bin/python'} 08:50:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:50:22 INFO - mozversion application_buildid: 20170209155318 08:50:22 INFO - mozversion application_changeset: 1c204002b9bedaa9fb386dda2031e32d664d6871 08:50:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:50:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:50:22 INFO - mozversion application_name: Firefox 08:50:22 INFO - mozversion application_remotingname: firefox-dev 08:50:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:22 INFO - mozversion application_vendor: Mozilla 08:50:22 INFO - mozversion application_version: 53.0a2 08:50:22 INFO - mozversion platform_buildid: 20170209155318 08:50:22 INFO - mozversion platform_changeset: 1c204002b9bedaa9fb386dda2031e32d664d6871 08:50:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:22 INFO - mozversion platform_version: 53.0a2 08:50:22 INFO - using testdate: 1486659022 08:50:22 INFO - actual date: 1486659022 08:50:22 INFO - starting webserver on 'localhost:47895' 08:50:22 INFO - SUITE-START | Running 2 tests 08:50:22 INFO - TEST-START | dromaeo_css 08:50:22 INFO - operating with platform_type : linux_ 08:50:22 INFO - Initialising browser for dromaeo_css test... 08:50:22 INFO - TEST-INFO | started process 5344 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL3W7JR/profile http://localhost:47895/getInfo.html) 08:50:26 INFO - PROCESS | 5344 | __metrics Screen width/height:1600/1200 08:50:26 INFO - PROCESS | 5344 | colorDepth:24 08:50:26 INFO - PROCESS | 5344 | Browser inner width/height: 1024/697 08:50:26 INFO - PROCESS | 5344 | __metrics 08:50:27 INFO - PROCESS | 5344 | 1486659027428 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:50:27 INFO - PROCESS | 5344 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:50:27 INFO - PROCESS | 5344 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:50:27 INFO - PROCESS | 5344 | console.error: PushService: 08:50:27 INFO - PROCESS | 5344 | stateChangeProcessEnqueue: Error transitioning state 08:50:27 INFO - PROCESS | 5344 | UnknownError 08:50:28 INFO - TEST-INFO | 5344: exit 0 08:50:28 INFO - Browser initialized. 08:50:28 INFO - Running cycle 1/1 for dromaeo_css test... 08:50:28 INFO - TEST-INFO | started process 5439 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpL3W7JR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:40 INFO - PROCESS | 5439 | RSS: Main: 137687040 08:50:40 INFO - PROCESS | 5439 | 08:53:40 INFO - PROCESS | 5439 | Cycle 1(1): loaded http://localhost:47895/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47895/tests/dromaeo/cssquery-ext.html) 08:53:41 INFO - PROCESS | 5439 | RSS: Main: 241455104 08:53:41 INFO - PROCESS | 5439 | 08:55:30 INFO - PROCESS | 5439 | ************************* 08:55:30 INFO - PROCESS | 5439 | A coding exception was thrown and uncaught in a Task. 08:55:30 INFO - PROCESS | 5439 | 08:55:30 ERROR - PROCESS | 5439 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:55:30 INFO - PROCESS | 5439 | Full stack: 08:55:30 INFO - PROCESS | 5439 | ************************* 08:56:43 INFO - PROCESS | 5439 | Cycle 1(1): loaded http://localhost:47895/tests/dromaeo/cssquery-ext.html (next: http://localhost:47895/tests/dromaeo/cssquery-jquery.html) 08:56:44 INFO - PROCESS | 5439 | RSS: Main: 220921856 08:56:44 INFO - PROCESS | 5439 | 08:59:46 INFO - PROCESS | 5439 | Cycle 1(1): loaded http://localhost:47895/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47895/tests/dromaeo/cssquery-mootools.html) 08:59:46 INFO - PROCESS | 5439 | RSS: Main: 215773184 08:59:46 INFO - PROCESS | 5439 | 09:02:48 INFO - PROCESS | 5439 | Cycle 1(1): loaded http://localhost:47895/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47895/tests/dromaeo/cssquery-prototype.html) 09:02:49 INFO - PROCESS | 5439 | RSS: Main: 170307584 09:02:49 INFO - PROCESS | 5439 | 09:05:52 INFO - PROCESS | 5439 | Cycle 1(1): loaded http://localhost:47895/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47895/tests/dromaeo/cssquery-yui.html) 09:05:52 INFO - PROCESS | 5439 | RSS: Main: 171773952 09:05:52 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | Cycle 1(1): loaded http://localhost:47895/tests/dromaeo/cssquery-yui.html (next: http://localhost:47895/tests/dromaeo/cssquery-dojo.html) 09:08:55 INFO - PROCESS | 5439 | RSS: Main: 265224192 09:08:55 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | __start_tp_report 09:08:55 INFO - PROCESS | 5439 | _x_x_mozilla_page_load 09:08:55 INFO - PROCESS | 5439 | _x_x_mozilla_page_load_details 09:08:55 INFO - PROCESS | 5439 | |i|pagename|runs| 09:08:55 INFO - PROCESS | 5439 | |0;dojo.html;3233.240188555689;3266.6896644818735;3280.097973057409;3292.094673964658;3301.926873766027;5411.91882121768;5436.673799572023;5441.782328706852;5447.264619276397;5467.589930755189;5512.39363669996;5527.723613819313;5539.9169012464845;5543.223948647183;5548.223248745179;4938.259261110827;4968.552830245278;5017.84946451607;5027.472115427864;5038.496150384951;4851.611871050308;4998.225275082368;5037.370328708911;5055.69665820052;5083.847484575469;4833.250846118857;4837.709737415748;4877.268409738532;4892.290617860408;4955.975220123894;4918.803247870081;4965;4967.180415231483;4967.403911530621;4999.850004499871;4879.682820616648;4891.8777030574165;4925.285833554115;4937.136001199811;4946.604271658259;5164.561012313958;5280.339957505312;5343.679379237258;5414;5426.104692725735;5843.211166492511;5843.649381037151;5847.093700476433;5851.268591426072;5873.2658417697785;5844.649321040752;5885.176075349455;5890.499307558863;5898.292204935392;5909.734061967221;5322.121849894763;5377.462253774591;5442.210879422461;5493.368262649807;5504.889902201933;5968.07494838292;5991.400859913973;5993.730282137314;6017.247844019497;6029.638221706711;5780.277465316835;5781.624194427349;5788.044972579477;5794.362620111784;5799.768009279666;5671.659700417906;5717.42825717425;5757.798477053283;5803.071508558611;5877.500412464989;5679.829605111853;5691.943080569225;5731.914021289684;5745.8563535911935;5768.134779782982;5725.4274572543245;5729.598928075078;5730.885382292414;5733.913991290134;5738.626989245769;4428.158649856517;4461.063176732922;4478.373027776114;4478.484974228006;4515.661325400608;3942.8225729842225;3965.8017099144927;3968.3452230381663;3992.181602771425;4026.7181297309494;5543.750531226105;5548.778048878;5557.249771280906;5562.554995600342;5575.080111781511;5652.152177173457;5676.801311954062;5771.538276937835;5773.480386765212;5820.30156381237;5602.13166959122;5664;5670.8015219467125;5673.74468148926;5697.202391665171;5011.323471331397;5035.194368900959;5040.7731652075745;5041.798328066836;5074.3657042869645;5853.64878107315;5928.940710592925;5933.970330148321;6071.9696401518595;6075.878482430327;5781.4507621775865;5895.469407753323;5917.556183286357;5919.141724450017;5934.465898069022;6004.42957918997;6038.245219347582;6041.78853740117;6041.939580604314;6048.153258543719;5581.5255703265675;5767.192593037062;5783.826485205451;5790.797322093875;5792.9710351447975;5704.258446401941;5754.625949313281;5761.07822748375;5772.336181339032;5850.795222167204;5662.178984047372;5701.942980570308;5709.400512946081;5710.685912274865;5710.914336284876;5683.090705487102;5694.202811606296;5695.345035320991;5700.230468886647;5700.372958974426;5486.945130548803;5598.608097433141;5663.433656634468;5690;5715.114157305624;5702.885942281048;5706;5711.171880077449;5713.600047996601;5743.913841292468;5609.27079479665;5615.522680572197;5621.100623900156;5623.69069701154;5635.633683810539 09:08:55 INFO - PROCESS | 5439 | |1;ext.html;565.9037963546197;611.8562137897592;612.6722203621064;633.8035209085184;660.4221306356937;37283.627163728386;40985.79507102464;41012.58987410125;49039.50960490394;49989;51630.741846290715;52100;52226;52229;52233;9333.53332333384;9571.856422153673;9577.61689532418;9577.664781732641;9580.185684216849;8926.2412694921;8982.820343593125;11153.10775137991;11323.32060076394;11327.263727857704;11475;11534.423278836066;11549.364784936824;11549.595764148256;11553.595624153157;11354.943225283903;11356.375399353032;11369.488373023234;11518.251313664585;11530;11240.606578769704;11291.378974156418;11333.603323883624;11339.489722962486;11357.204995650307;48929.510704892855;49401.50598494005;49401.752991235175;49414;49447.75276123633;4527.660425468071;4538.773061346952;4592.7933243004145;4668.092088906589;4683.110209060302;35898.64101358979;36213.275734485425;36475.452868207;36517.26965460702;37633.62366376328;4585.174668559658;4603.028570007695;4639.420072490939;4655.88360290992;4686.601638860701;362133;362970;364202;364610;365713.9999999973;68759;69318.30681693219;69347;69435.65282173658;69460.65269673619;72722;72740.63629681924;72744;72904;73022.63488682522;19806.60386792256;20390.592188156155;20445;20447.4888127798;20458.897705511674;14149.08030977983;14350.641233969234;14354.569362919128;14535.055221410574;14697.853021469862;18809.341673041377;18910.108989101256;19168;19873.205071797256;19976.50058748543;6731.360520750521;6814.965925170342;6892.931070689229;6941.271166527543;7009.264027277062;987.3927534566191;1001.2290536287019;1002.8395456726888;1028;1036.1969473657975;33080.66919330824;34041.82979085089;34352.82823585916;34597;35008;1141.2182654881433;1182.2788099259444;1185.3421351150193;1207.8671346151916;1223.2049168040844;17072.743908841378;18139.90930045341;18168.727469087975;18210.271589136286;18249.63500729978;533514;537531;539301;540425;540493;67319.66340168366;67455.3254467449;67479.66260168668;67497;67527;1529.3806008566548;1545.366399776109;1546.2191593245338;1573.1426372626945;1594.378192504924;32182.19544511406;33580.328393431504;33662.49506257457;33738.83130584331;33794.15514612154;55605;57435.138472923776;57449.712751435974;57483.42516574948;57506.7124664374;407688;409691;409998;410589;410840;40055.79972100121;41136;42172.36741448942;43759.562404375545;45119.09761804811;47277.76361118173;53047;53448.73275633597;53543.73228133834;53859.19211211757;43253.783731081145;43522.697898231985;45120;46008.769956150005;46823.53176468192;44322.778386107864;44389;44626.77686611676;45848;46124.538754612026;44197.55802442063;44213.557864420945;44474.3328850061;44629.330560042275;44636.10727785361;37089.25821483609;38467.80766096152;38629;39019;39312.41031384589;28091.43817123687;28427.801542324407;28767;28904.42191156123;29075.273118172216 09:08:55 INFO - PROCESS | 5439 | |2;jquery.html;436.5067473754657;439.3980247061526;440.6100600968146;449.842555105713;457.54931392578305;20441.897790511044;20967.637103588277;22224.77775222247;22370.66444003341;22447.3265802026;22493;23399.064037438526;23497.765022349773;23574.646380304268;23726.881365593148;5912.911306330398;6113.205283313163;6113.510919126482;6122.571420000601;6124.846878828021;6219.471344935686;6383;6396.1045453636525;6397.488200943936;6403.903941440882;6190.597611155283;6308.526860485459;6346.555741098125;6361.173047503818;6388.712507937131;6297.4017468340435;6323.841903952392;6340.682965851712;6343.984962406016;6346.809595712136;5586.469285417878;5805.7387417566315;5838.824835254949;5881.264841894763;5890.793822216202;15446.459373921858;15565.29956151976;24534.263972080866;24548.649824259835;25982.090626828158;2720.183944816547;2786.6098746175553;2796.133198708407;2807.354308509034;2808.354078561942;10660.786784264348;10971.725706857313;11078.501467433904;11599.76800463986;11683.941580292128;2029.7462817147857;2034.52188735647;2075.2114196605344;2078.054485209241;2092.79072092791;88081.55959220162;100357.4982125092;107222.3916641251;107632.4618376911;109007.9999999992;12156.392180390945;12299.200551964093;12425.130240883233;12652.81020784689;12664.74670506603;18404.539886502676;18419.171137298854;18440.354587589638;18440.446786596425;18451.538711532317;9200.723978280663;9278.582463789146;9292.210162136158;9373.109554592304;9463.101005404476;8908.376413651113;8953.776155596162;9002.594883230271;9131.634734610541;9178.724638260863;5349.732513374316;5408.864778380494;5438.2386465894015;5462.699551524624;5518.889622207534;2179.31351624241;2185.737711474633;2198.8131008864107;2204.845660803728;2281.178775640768;923.676713150392;945.5792172483317;951.7023513525667;952.3904700991371;959.3332633819568;12983.545575904798;19009.714854277197;21487.431102996965;21896.65019492948;21921.780782192294;526.5182358142257;536.07259441167;538.9248449343585;540.227474711159;540.8755986123214;6927.480438967098;7008.26413226614;7035.472339574552;7138.964305178441;7139.92860071403;651602;678344;752866;761105;791058;112449.43775281071;116989.41505292589;117356;118206.40896795462;118946;534.0680512505702;540.5702466539111;542.5903442900664;542.8724249801294;546.232543276689;12202.14584979043;12350.75298494043;12376.195547289397;12607.558735444216;12635.1787133836;45781.31328030016;46568.30147547857;47219.763901180275;47862.28206576974;48603.27095093647;69050;69740.65129674319;69753;69975;71155;6569.014647802676;7685.423593230642;9505.66730164441;9594.568244429158;9857.457839818879;11000.504977276201;14459.855401445851;14550.199739014033;14812.111273323635;14814;11949.2830430171;12341.876581234073;12381.814272786096;12487.063470239587;12807.231566106064;12282;12349.073819463756;12477.438515266653;12543.121981461574;12800;12260.938695306822;12486.500539978299;12582.30797306157;12625.558105466265;12732.809007865075;9843.753906152117;9862;9967.800643986935;10089.697309080739;10247.84628230592;9495.525223738923;11799.76400472003;12449.564265250672;12496;12870.292133932724 09:08:55 INFO - PROCESS | 5439 | |3;mootools.html;1367.6991061966364;1481.733288008158;1511.833698293187;1513.4475916290553;1519.3466809272013;7626.275503827134;8290.875636865445;8618.35362347824;8622.698205562807;8884.689035883745;8636.481811091324;8638.049814520398;8681.782955426117;8686.609102590382;8687.652493900236;2086.1968142265187;2157.6871353653787;2366.2546297916188;2413.4811015631635;2413.8775469406723;2326.255598208574;2390.3187591536416;2394.078279862248;2406.3984003999;2407.061246114008;2365.787079162875;2378.595638741418;2383.9761602384015;2389.354874183969;2396.7483414241515;2356.3402247370764;2358.233574088428;2370.2415227127326;2379.060271192869;2380.3454050136265;2300.8389412741117;2337.8714170720605;2341.695579574661;2342.4143964009;2342.941426464352;8424.578771061422;8441.873371899426;8445.493270403735;8485.448445851061;8491.108433614443;5192.7403629818355;5334.973325133349;5369.543588794957;5379.38137114233;5384.1923711443205;3296.2583418730833;3559.94660080099;3580.1407662160896;3602.5676918769655;3604.9279014419826;1467.889908256885;1468.177820420568;1511.2670354877876;1554.6657468644296;1563.9765403518957;11165.776684466266;11265.436728163559;11283.210175287733;11372.943135284271;11394.943025284903;4747.255785934059;4778.832740854123;4866.440359358719;4885.511448855086;4894.437139728905;9904.900950990543;9915.702528924145;9920.4543750093;9921.950390248003;9937.45344006072;5401.864953376118;5555.50000499957;5557.527610153102;5557.638753481011;5568.470995255444;4038.5557588665224;4874.8781280467565;4958.752062396866;5055.368078990126;5087.949120508822;4159.625633692982;4181.644560212355;4226.513950895686;4283.850065247701;4286.87139385819;1384.1694983010113;1389.173441802126;1404.676924307415;1409.2037998530798;1413.724323756878;1045.9163266938626;1046.4715318763976;1052.321252791945;1056.6777132974516;1066.274933045521;5841.386654401312;5935.2877654681715;5959.22530071088;6013.188219590387;6096.695165241809;647.090837373491;652.9706163222667;657.4674513643964;658.1411258307954;659.1102012283379;3465.653434656514;3511.719062474996;3533.222691007974;3535.911602209914;3575.1598374382;9535.761605959906;9874.753131171778;9914.058164474363;9932.751681208028;9938.950305248427;9703.805923881624;9728.562214700356;9746.853797193047;9838.754031149136;9870.555824987892;7591.772246832604;7813.257740514642;7868.213178682086;7911.604419779103;7959.801004974806;5739.655620662774;5918.940810592011;6010.038393856962;6028.638281703112;6090.3605121461605;9399.34204605671;9474.763130921781;9497.287703422133;9510.714678559654;9608.855867162138;9387.10822471864;9764.707058788248;9785.266105042292;9796.412215267106;9803.558839852367;6841.350071743176;6933.861322773617;6943.305669433016;7470.439717021137;7615.276548727862;7735.497192682457;7792.961035194788;7890.132085470708;7992.160823113722;8000.239977202156;7390.9260907390235;7393;7457.067866516685;7472.626368681435;7531.472796904164;7244.094488188976;7319.707211711472;7321.084864391951;7331.670074846751;7353.558786472829;6596.80209593713;6653.301403352578;6666.400023997669;6666.733320669057;6670.433013193933;4978.477259887804;5033.245013248042;5072.898542029213;5082.491750824888;5271.762770675252;5089.465606111305;5101.566366858859;5231.738413079407;5235.60732945023;5346.625736198429 09:08:55 INFO - PROCESS | 5439 | |4;prototype.html;416.9874903752887;422.8794793483857;425.93823895535166;433.26994015084585;434.77176975045484;5392.784288628455;5398.91901621476;5403.567714582834;5484;5521.67479804847;5143.331366922304;5349.54528865046;5362.43694412087;5376;5395.81114660987;3961.7028722845757;4080.5511393746665;4082.2447847148223;4145.295299799026;4241.915161696764;4151.854685086023;4196.328587426013;4197.118605092919;4198.286291330481;4198.769067701275;4167.4790651168605;4181.602747738975;4186.790660466964;4206.621404073633;4206.684498662597;4022.6178513041214;4036.2129384769983;4136.276151673445;4167.2082304362375;4203.726757760735;3700.834237215269;3707.461403517537;3709.2581483703366;3712.6658600725796;3713.591504934455;5211.557017653504;5231.424543300234;5240.842774716764;5257.316548848626;5269.420363759983;4727.763611819395;4829.275608658694;4843.515648435164;4875.902481950377;4886.242632391985;5613.550915926716;5624.184493248497;5631.239782629334;5634.887302253973;5643.830685079454;4135.669146468305;4136.400221967828;4150.418941348214;4172.311568591179;4185.644220241283;5703.65778053314;5765.077587586008;5778.3531693467585;5865.166428546729;5889.411058894119;4208.978955105266;4218.514870789899;4222.725522841006;4223.184030866695;4232.767197804151;4269.359596060615;4283.464566929134;4302.913941721211;4311.116221174651;4314.827406903752;4764.809407623657;4833.661643684979;4844.806207751651;4859.2954021666665;4894.632902532324;5671.177679236487;5677.233573467529;5690.630109042899;5695.345035320991;5712.228849105318;1412.6044707481922;1421.85070567591;1422.1467119728288;1424.7150569886064;1439.8488158743392;922.7911436018837;972.2707969023232;1007.2294694558668;1019.1591936652292;1037.818381783185;5240.213967904845;5360.571154307646;5481.054225749881;5491.407491827325;5505.146702261156;5749.367569567345;5760.423957604206;5765.625234359753;5765.981906332834;5782.537397008229;5404.891902161935;5486.972565137228;5528.226048353234;5556.472135147154;5562.360328562187;2952.578885314022;2972.2866512036953;2983.9407010510954;2987.7750122449693;3000.8949686760857;5775.422457754275;5821.825345239649;5831.416858314219;5839.270091238595;5854.619449735754;5453.509184173444;5709.543236541067;5718.256626638594;5719.571032172604;5721.713914304268;5763.682997435098;5807.651540907559;5826;5831.329397119301;5851.151583020353;5346.648097114787;5469.972650136724;5598.580106492111;5617.7191140442;5629.718514074198;5586.497215250647;5591.720413979204;5606.523445507178;5614.378725721968;5641;5844.795432159854;5849.303701926564;5875.265591801025;5875.73559189846;5877.529797616095;3974.940375894422;4070.5929407059057;4091.324931386288;4112.403701463331;4113.354658136769;5678.432156784289;5690.2887139107615;5690.829275121753;5691.121929763251;5726.026575482095;5360;5506.669599824023;5547.639403438763;5626.5780066496;5659;5402.405735369185;5422.593305502182;5434.592405569519;5458.563314934875;5477.780888764405;5414.431484694051;5424.674519528841;5429.67421954684;5435.266239057677;5515.290260019407;5406.972965135149;5507.614466987273;5557.472040156179;5610.915836262541;5621.156826476062;5531.695756733418;5536.2249285100925;5538.984236428718;5540.252065971204;5540.390557038642 09:08:55 INFO - PROCESS | 5439 | |5;yui.html;805.359739007489;818.0347190315433;828.5650033732289;899.3794281945455;899.415380002998;16316.755248671265;17845.64308713825;17917;17930.641387172265;17934.46196614102;15423.305951232194;15506.84493155071;15545.222738863069;15567.065976041446;15587.84412155881;708.2492557888629;722.5809030762146;813.9145389734082;831.085805613826;836.7740710008294;842.2335674536173;843.9409241353108;844.9450785698909;845.3870943565927;845.6025667936091;886.7694399456155;887.0198430734043;887.8357503861774;888.2804928008302;888.4003297774009;741.3772431157827;746.6565379925268;857.0914830279861;872.1148034744675;881.4446898453965;636.150738763751;642.1555654314561;642.4507046475289;642.5116911147514;643.2184895352123;16053.438129665406;16130.032198068153;16557.91721041399;16605.08672023038;16630.916845415817;15112.924435377752;15836.445724399706;15873.444429444913;16034.919825400915;16132.59668508285;12834.229946203164;12977.286249256287;12991.740165196738;14043;14465.566033019026;4789.760511974422;4804.5916097131785;4828.324034635154;4846.612271018345;4980.551750342486;83026.58486707527;83751.581242094;84622.57688711517;84746.57626711827;84949.57525212396;12100.878991209975;12274.754504909852;12388.132830701945;12489.687757806127;12548.686282843002;22776;23055.42361440944;23255.651165232535;23297.650535241984;23474.530509389715;8387.748367548984;8715.172058654418;8783.429077110095;8800.075992020746;8808.251298639687;4533.54664533544;4534.070515544306;4604.263317869125;4635.930461043087;4675.579197872208;3891.105045839473;3964.3458829292845;3968.8412463501713;3969.761814291152;3977.900552486211;691.1498856406648;691.2707094015761;697.849962258117;705.463847475922;705.4814711187273;830.1781236575868;846.8899043124478;868.452874688942;871.3639043498155;874.9737507874773;11429.542818287175;11507.827382589268;11792.52829886795;11938.52245910154;12292.754144917231;645.9547831651739;647.9967600161968;667.3826710293057;667.846395329077;670.5406796344422;5275.419703832576;5351.812686555952;5357.196420536873;5423.620346575702;5460.699661518575;78708.6064569685;79254;79728.60135699285;80840;83539.16460835314;70291;70815.64592177006;71733.28266717267;72197;73655;55808.16287755687;60074;60875.69562152249;61156.38843611596;61384.38615613876;10096.69709908704;10218.795624087625;10344.534495947852;10510.737231569272;10519.474026298807;42112.789436052626;43886.12227755491;44446.3333050011;45258.547414525434;45748.54251457576;51039.48960510496;52235.47764522306;53426;54331.18503222534;54978.725106374215;14325.140491570539;14501.854981450473;14574.125552466887;14598.854011459749;14797.630059248175;15985.920070399574;16041.518754437386;16268.755968660244;16298.67402651964;16726.08006559651;14313.856861431668;15649.765253520978;15946.760798587798;16079.517614471584;16629.66740665156;15588.610284742972;15771.921140394224;16321.59196020109;16409.097499637635;16503.17484125813;15518.301676424362;15529.145896975775;15667.216639168224;16320.591985200464;16440.506784796475;11929.19471453503;12063.396830158632;12458.813117803058;13245.867541324464;13392.799108013582;12583;12589.118761686594;12616.621501354974;12820.294883781482;13032.674183145118 09:08:55 INFO - PROCESS | 5439 | __end_tp_report 09:08:55 INFO - PROCESS | 5439 | __start_cc_report 09:08:55 INFO - PROCESS | 5439 | _x_x_mozilla_cycle_collect,960 09:08:55 INFO - PROCESS | 5439 | __end_cc_report 09:08:55 INFO - PROCESS | 5439 | __startTimestamp1486660135446__endTimestamp 09:08:55 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | ------- Summary: start ------- 09:08:55 INFO - PROCESS | 5439 | Number of tests: 6 09:08:55 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | [#0] dojo.html Cycles:165 Average:5416.18 Median:5664.00 stddev:598.10 (10.6%) stddev-sans-first:574.90 09:08:55 INFO - PROCESS | 5439 | Values: 3233.2 3266.7 3280.1 3292.1 3301.9 5411.9 5436.7 5441.8 5447.3 5467.6 5512.4 5527.7 5539.9 5543.2 5548.2 4938.3 4968.6 5017.8 5027.5 5038.5 4851.6 4998.2 5037.4 5055.7 5083.8 4833.3 4837.7 4877.3 4892.3 4956.0 4918.8 4965.0 4967.2 4967.4 4999.9 4879.7 4891.9 4925.3 4937.1 4946.6 5164.6 5280.3 5343.7 5414.0 5426.1 5843.2 5843.6 5847.1 5851.3 5873.3 5844.6 5885.2 5890.5 5898.3 5909.7 5322.1 5377.5 5442.2 5493.4 5504.9 5968.1 5991.4 5993.7 6017.2 6029.6 5780.3 5781.6 5788.0 5794.4 5799.8 5671.7 5717.4 5757.8 5803.1 5877.5 5679.8 5691.9 5731.9 5745.9 5768.1 5725.4 5729.6 5730.9 5733.9 5738.6 4428.2 4461.1 4478.4 4478.5 4515.7 3942.8 3965.8 3968.3 3992.2 4026.7 5543.8 5548.8 5557.2 5562.6 5575.1 5652.2 5676.8 5771.5 5773.5 5820.3 5602.1 5664.0 5670.8 5673.7 5697.2 5011.3 5035.2 5040.8 5041.8 5074.4 5853.6 5928.9 5934.0 6072.0 6075.9 5781.5 5895.5 5917.6 5919.1 5934.5 6004.4 6038.2 6041.8 6041.9 6048.2 5581.5 5767.2 5783.8 5790.8 5793.0 5704.3 5754.6 5761.1 5772.3 5850.8 5662.2 5701.9 5709.4 5710.7 5710.9 5683.1 5694.2 5695.3 5700.2 5700.4 5486.9 5598.6 5663.4 5690.0 5715.1 5702.9 5706.0 5711.2 5713.6 5743.9 5609.3 5615.5 5621.1 5623.7 5635.6 09:08:55 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | [#1] ext.html Cycles:180 Average:63076.48 Median:225988.00 stddev:117031.14 (51.8%) stddev-sans-first:117265.33 09:08:55 INFO - PROCESS | 5439 | Values: 565.9 611.9 612.7 633.8 660.4 37283.6 40985.8 41012.6 49039.5 49989.0 51630.7 52100.0 52226.0 52229.0 52233.0 9333.5 9571.9 9577.6 9577.7 9580.2 8926.2 8982.8 11153.1 11323.3 11327.3 11475.0 11534.4 11549.4 11549.6 11553.6 11354.9 11356.4 11369.5 11518.3 11530.0 11240.6 11291.4 11333.6 11339.5 11357.2 48929.5 49401.5 49401.8 49414.0 49447.8 4527.7 4538.8 4592.8 4668.1 4683.1 35898.6 36213.3 36475.5 36517.3 37633.6 4585.2 4603.0 4639.4 4655.9 4686.6 362133.0 362970.0 364202.0 364610.0 365714.0 68759.0 69318.3 69347.0 69435.7 69460.7 72722.0 72740.6 72744.0 72904.0 73022.6 19806.6 20390.6 20445.0 20447.5 20458.9 14149.1 14350.6 14354.6 14535.1 14697.9 18809.3 18910.1 19168.0 19873.2 19976.5 6731.4 6815.0 6892.9 6941.3 7009.3 987.4 1001.2 1002.8 1028.0 1036.2 33080.7 34041.8 34352.8 34597.0 35008.0 1141.2 1182.3 1185.3 1207.9 1223.2 17072.7 18139.9 18168.7 18210.3 18249.6 533514.0 537531.0 539301.0 540425.0 540493.0 67319.7 67455.3 67479.7 67497.0 67527.0 1529.4 1545.4 1546.2 1573.1 1594.4 32182.2 33580.3 33662.5 33738.8 33794.2 55605.0 57435.1 57449.7 57483.4 57506.7 407688.0 409691.0 409998.0 410589.0 410840.0 40055.8 41136.0 42172.4 43759.6 45119.1 47277.8 53047.0 53448.7 53543.7 53859.2 43253.8 43522.7 45120.0 46008.8 46823.5 44322.8 44389.0 44626.8 45848.0 46124.5 44197.6 44213.6 44474.3 44629.3 44636.1 37089.3 38467.8 38629.0 39019.0 39312.4 28091.4 28427.8 28767.0 28904.4 29075.3 09:08:55 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | [#2] jquery.html Cycles:180 Average:37685.14 Median:437.95 stddev:120010.37 (27402.6%) stddev-sans-first:120314.43 09:08:55 INFO - PROCESS | 5439 | Values: 436.5 439.4 440.6 449.8 457.5 20441.9 20967.6 22224.8 22370.7 22447.3 22493.0 23399.1 23497.8 23574.6 23726.9 5912.9 6113.2 6113.5 6122.6 6124.8 6219.5 6383.0 6396.1 6397.5 6403.9 6190.6 6308.5 6346.6 6361.2 6388.7 6297.4 6323.8 6340.7 6344.0 6346.8 5586.5 5805.7 5838.8 5881.3 5890.8 15446.5 15565.3 24534.3 24548.6 25982.1 2720.2 2786.6 2796.1 2807.4 2808.4 10660.8 10971.7 11078.5 11599.8 11683.9 2029.7 2034.5 2075.2 2078.1 2092.8 88081.6 100357.5 107222.4 107632.5 109008.0 12156.4 12299.2 12425.1 12652.8 12664.7 18404.5 18419.2 18440.4 18440.4 18451.5 9200.7 9278.6 9292.2 9373.1 9463.1 8908.4 8953.8 9002.6 9131.6 9178.7 5349.7 5408.9 5438.2 5462.7 5518.9 2179.3 2185.7 2198.8 2204.8 2281.2 923.7 945.6 951.7 952.4 959.3 12983.5 19009.7 21487.4 21896.7 21921.8 526.5 536.1 538.9 540.2 540.9 6927.5 7008.3 7035.5 7139.0 7139.9 651602.0 678344.0 752866.0 761105.0 791058.0 112449.4 116989.4 117356.0 118206.4 118946.0 534.1 540.6 542.6 542.9 546.2 12202.1 12350.8 12376.2 12607.6 12635.2 45781.3 46568.3 47219.8 47862.3 48603.3 69050.0 69740.7 69753.0 69975.0 71155.0 6569.0 7685.4 9505.7 9594.6 9857.5 11000.5 14459.9 14550.2 14812.1 14814.0 11949.3 12341.9 12381.8 12487.1 12807.2 12282.0 12349.1 12477.4 12543.1 12800.0 12260.9 12486.5 12582.3 12625.6 12732.8 9843.8 9862.0 9967.8 10089.7 10247.8 9495.5 11799.8 12449.6 12496.0 12870.3 09:08:55 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | [#3] mootools.html Cycles:180 Average:5603.04 Median:5478.68 stddev:3019.75 (55.1%) stddev-sans-first:3011.44 09:08:55 INFO - PROCESS | 5439 | Values: 1367.7 1481.7 1511.8 1513.4 1519.3 7626.3 8290.9 8618.4 8622.7 8884.7 8636.5 8638.0 8681.8 8686.6 8687.7 2086.2 2157.7 2366.3 2413.5 2413.9 2326.3 2390.3 2394.1 2406.4 2407.1 2365.8 2378.6 2384.0 2389.4 2396.7 2356.3 2358.2 2370.2 2379.1 2380.3 2300.8 2337.9 2341.7 2342.4 2342.9 8424.6 8441.9 8445.5 8485.4 8491.1 5192.7 5335.0 5369.5 5379.4 5384.2 3296.3 3559.9 3580.1 3602.6 3604.9 1467.9 1468.2 1511.3 1554.7 1564.0 11165.8 11265.4 11283.2 11372.9 11394.9 4747.3 4778.8 4866.4 4885.5 4894.4 9904.9 9915.7 9920.5 9922.0 9937.5 5401.9 5555.5 5557.5 5557.6 5568.5 4038.6 4874.9 4958.8 5055.4 5087.9 4159.6 4181.6 4226.5 4283.9 4286.9 1384.2 1389.2 1404.7 1409.2 1413.7 1045.9 1046.5 1052.3 1056.7 1066.3 5841.4 5935.3 5959.2 6013.2 6096.7 647.1 653.0 657.5 658.1 659.1 3465.7 3511.7 3533.2 3535.9 3575.2 9535.8 9874.8 9914.1 9932.8 9939.0 9703.8 9728.6 9746.9 9838.8 9870.6 7591.8 7813.3 7868.2 7911.6 7959.8 5739.7 5918.9 6010.0 6028.6 6090.4 9399.3 9474.8 9497.3 9510.7 9608.9 9387.1 9764.7 9785.3 9796.4 9803.6 6841.4 6933.9 6943.3 7470.4 7615.3 7735.5 7793.0 7890.1 7992.2 8000.2 7390.9 7393.0 7457.1 7472.6 7531.5 7244.1 7319.7 7321.1 7331.7 7353.6 6596.8 6653.3 6666.4 6666.7 6670.4 4978.5 5033.2 5072.9 5082.5 5271.8 5089.5 5101.6 5231.7 5235.6 5346.6 09:08:55 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | [#4] prototype.html Cycles:180 Average:4718.47 Median:5404.23 stddev:1360.01 (25.2%) stddev-sans-first:1324.95 09:08:55 INFO - PROCESS | 5439 | Values: 417.0 422.9 425.9 433.3 434.8 5392.8 5398.9 5403.6 5484.0 5521.7 5143.3 5349.5 5362.4 5376.0 5395.8 3961.7 4080.6 4082.2 4145.3 4241.9 4151.9 4196.3 4197.1 4198.3 4198.8 4167.5 4181.6 4186.8 4206.6 4206.7 4022.6 4036.2 4136.3 4167.2 4203.7 3700.8 3707.5 3709.3 3712.7 3713.6 5211.6 5231.4 5240.8 5257.3 5269.4 4727.8 4829.3 4843.5 4875.9 4886.2 5613.6 5624.2 5631.2 5634.9 5643.8 4135.7 4136.4 4150.4 4172.3 4185.6 5703.7 5765.1 5778.4 5865.2 5889.4 4209.0 4218.5 4222.7 4223.2 4232.8 4269.4 4283.5 4302.9 4311.1 4314.8 4764.8 4833.7 4844.8 4859.3 4894.6 5671.2 5677.2 5690.6 5695.3 5712.2 1412.6 1421.9 1422.1 1424.7 1439.8 922.8 972.3 1007.2 1019.2 1037.8 5240.2 5360.6 5481.1 5491.4 5505.1 5749.4 5760.4 5765.6 5766.0 5782.5 5404.9 5487.0 5528.2 5556.5 5562.4 2952.6 2972.3 2983.9 2987.8 3000.9 5775.4 5821.8 5831.4 5839.3 5854.6 5453.5 5709.5 5718.3 5719.6 5721.7 5763.7 5807.7 5826.0 5831.3 5851.2 5346.6 5470.0 5598.6 5617.7 5629.7 5586.5 5591.7 5606.5 5614.4 5641.0 5844.8 5849.3 5875.3 5875.7 5877.5 3974.9 4070.6 4091.3 4112.4 4113.4 5678.4 5690.3 5690.8 5691.1 5726.0 5360.0 5506.7 5547.6 5626.6 5659.0 5402.4 5422.6 5434.6 5458.6 5477.8 5414.4 5424.7 5429.7 5435.3 5515.3 5407.0 5507.6 5557.5 5610.9 5621.2 5531.7 5536.2 5539.0 5540.3 5540.4 09:08:55 INFO - PROCESS | 5439 | 09:08:55 INFO - PROCESS | 5439 | [#5] yui.html Cycles:180 Average:18571.71 Median:25141.31 stddev:22789.60 (90.6%) stddev-sans-first:22814.47 09:08:55 INFO - PROCESS | 5439 | Values: 805.4 818.0 828.6 899.4 899.4 16316.8 17845.6 17917.0 17930.6 17934.5 15423.3 15506.8 15545.2 15567.1 15587.8 708.2 722.6 813.9 831.1 836.8 842.2 843.9 844.9 845.4 845.6 886.8 887.0 887.8 888.3 888.4 741.4 746.7 857.1 872.1 881.4 636.2 642.2 642.5 642.5 643.2 16053.4 16130.0 16557.9 16605.1 16630.9 15112.9 15836.4 15873.4 16034.9 16132.6 12834.2 12977.3 12991.7 14043.0 14465.6 4789.8 4804.6 4828.3 4846.6 4980.6 83026.6 83751.6 84622.6 84746.6 84949.6 12100.9 12274.8 12388.1 12489.7 12548.7 22776.0 23055.4 23255.7 23297.7 23474.5 8387.7 8715.2 8783.4 8800.1 8808.3 4533.5 4534.1 4604.3 4635.9 4675.6 3891.1 3964.3 3968.8 3969.8 3977.9 691.1 691.3 697.8 705.5 705.5 830.2 846.9 868.5 871.4 875.0 11429.5 11507.8 11792.5 11938.5 12292.8 646.0 648.0 667.4 667.8 670.5 5275.4 5351.8 5357.2 5423.6 5460.7 78708.6 79254.0 79728.6 80840.0 83539.2 70291.0 70815.6 71733.3 72197.0 73655.0 55808.2 60074.0 60875.7 61156.4 61384.4 10096.7 10218.8 10344.5 10510.7 10519.5 42112.8 43886.1 44446.3 45258.5 45748.5 51039.5 52235.5 53426.0 54331.2 54978.7 14325.1 14501.9 14574.1 14598.9 14797.6 15985.9 16041.5 16268.8 16298.7 16726.1 14313.9 15649.8 15946.8 16079.5 16629.7 15588.6 15771.9 16321.6 16409.1 16503.2 15518.3 15529.1 15667.2 16320.6 16440.5 11929.2 12063.4 12458.8 13245.9 13392.8 12583.0 12589.1 12616.6 12820.3 13032.7 09:08:55 INFO - PROCESS | 5439 | -------- Summary: end -------- 09:08:55 INFO - PROCESS | 5439 | 09:08:56 INFO - TEST-INFO | 5439: exit 0 09:08:56 INFO - TEST-OK | dromaeo_css | took 1113551ms 09:08:56 INFO - TEST-START | kraken 09:08:56 INFO - operating with platform_type : linux_ 09:08:56 INFO - Initialising browser for kraken test... 09:08:56 INFO - TEST-INFO | started process 5756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY1AXf6/profile http://localhost:47895/getInfo.html) 09:09:00 INFO - PROCESS | 5756 | __metrics Screen width/height:1600/1200 09:09:00 INFO - PROCESS | 5756 | colorDepth:24 09:09:00 INFO - PROCESS | 5756 | Browser inner width/height: 1024/697 09:09:00 INFO - PROCESS | 5756 | __metrics 09:09:00 INFO - PROCESS | 5756 | 1486660140965 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:09:01 INFO - PROCESS | 5756 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:09:01 INFO - PROCESS | 5756 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:09:02 INFO - TEST-INFO | 5756: exit 0 09:09:02 INFO - Browser initialized. 09:09:02 INFO - Running cycle 1/1 for kraken test... 09:09:02 INFO - TEST-INFO | started process 5846 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY1AXf6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:13 INFO - PROCESS | 5846 | RSS: Main: 138338304 09:09:13 INFO - PROCESS | 5846 | 09:09:16 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47895/tests/kraken/driver.html?testName=audio-beat-detection) 09:09:17 INFO - PROCESS | 5846 | RSS: Main: 182210560 09:09:17 INFO - PROCESS | 5846 | 09:09:19 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47895/tests/kraken/driver.html?testName=audio-dft) 09:09:20 INFO - PROCESS | 5846 | RSS: Main: 172068864 09:09:20 INFO - PROCESS | 5846 | 09:09:23 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47895/tests/kraken/driver.html?testName=audio-fft) 09:09:23 INFO - PROCESS | 5846 | RSS: Main: 264331264 09:09:23 INFO - PROCESS | 5846 | 09:09:25 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47895/tests/kraken/driver.html?testName=audio-oscillator) 09:09:26 INFO - PROCESS | 5846 | RSS: Main: 170311680 09:09:26 INFO - PROCESS | 5846 | 09:09:28 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47895/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:29 INFO - PROCESS | 5846 | RSS: Main: 173207552 09:09:29 INFO - PROCESS | 5846 | 09:09:32 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47895/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:32 INFO - PROCESS | 5846 | RSS: Main: 211841024 09:09:32 INFO - PROCESS | 5846 | 09:09:36 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47895/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:36 INFO - PROCESS | 5846 | RSS: Main: 209428480 09:09:36 INFO - PROCESS | 5846 | 09:09:39 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47895/tests/kraken/driver.html?testName=json-parse-financial) 09:09:40 INFO - PROCESS | 5846 | RSS: Main: 208461824 09:09:40 INFO - PROCESS | 5846 | 09:09:41 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47895/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:42 INFO - PROCESS | 5846 | RSS: Main: 170717184 09:09:42 INFO - PROCESS | 5846 | 09:09:43 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47895/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:44 INFO - PROCESS | 5846 | RSS: Main: 178089984 09:09:44 INFO - PROCESS | 5846 | 09:09:46 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47895/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:47 INFO - PROCESS | 5846 | RSS: Main: 186732544 09:09:47 INFO - PROCESS | 5846 | 09:09:49 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:50 INFO - PROCESS | 5846 | RSS: Main: 197103616 09:09:50 INFO - PROCESS | 5846 | 09:09:52 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:53 INFO - PROCESS | 5846 | RSS: Main: 192925696 09:09:53 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | Cycle 1(1): loaded http://localhost:47895/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47895/tests/kraken/driver.html?testName=ai-astar) 09:09:55 INFO - PROCESS | 5846 | RSS: Main: 244789248 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | __start_tp_report 09:09:55 INFO - PROCESS | 5846 | _x_x_mozilla_page_load 09:09:55 INFO - PROCESS | 5846 | _x_x_mozilla_page_load_details 09:09:55 INFO - PROCESS | 5846 | |i|pagename|runs| 09:09:55 INFO - PROCESS | 5846 | |0;ai-astar;110;113;111;110;111;112;110;111;110;112 09:09:55 INFO - PROCESS | 5846 | |1;audio-beat-detection;147;120;119;155;117;117;117;118;132;121 09:09:55 INFO - PROCESS | 5846 | |2;audio-dft;159;161;160;161;161;161;160;160;159;160 09:09:55 INFO - PROCESS | 5846 | |3;audio-fft;74;76;75;115;75;75;76;78;94;75 09:09:55 INFO - PROCESS | 5846 | |4;audio-oscillator;84;83;110;83;83;83;99;83;82;105 09:09:55 INFO - PROCESS | 5846 | |5;imaging-gaussian-blur;117;118;117;118;117;117;117;117;116;117 09:09:55 INFO - PROCESS | 5846 | |6;imaging-darkroom;148;147;146;146;147;147;147;147;146;146 09:09:55 INFO - PROCESS | 5846 | |7;imaging-desaturate;95;96;96;96;95;95;95;96;96;95 09:09:55 INFO - PROCESS | 5846 | |8;json-parse-financial;51;49;49;51;51;49;50;50;51;50 09:09:55 INFO - PROCESS | 5846 | |9;json-stringify-tinderbox;63;64;64;64;64;63;62;64;64;65 09:09:55 INFO - PROCESS | 5846 | |10;stanford-crypto-aes;76;76;76;80;77;77;77;77;80;77 09:09:55 INFO - PROCESS | 5846 | |11;stanford-crypto-ccm;121;124;126;121;124;125;121;121;160;127 09:09:55 INFO - PROCESS | 5846 | |12;stanford-crypto-pbkdf2;159;161;163;158;164;156;155;167;162;155 09:09:55 INFO - PROCESS | 5846 | |13;stanford-crypto-sha256-iterative;62;61;60;60;60;61;59;60;60;60 09:09:55 INFO - PROCESS | 5846 | __end_tp_report 09:09:55 INFO - PROCESS | 5846 | __start_cc_report 09:09:55 INFO - PROCESS | 5846 | _x_x_mozilla_cycle_collect,1535 09:09:55 INFO - PROCESS | 5846 | __end_cc_report 09:09:55 INFO - PROCESS | 5846 | __startTimestamp1486660195371__endTimestamp 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | ------- Summary: start ------- 09:09:55 INFO - PROCESS | 5846 | Number of tests: 14 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:1.05 (0.9%) stddev-sans-first:1.05 09:09:55 INFO - PROCESS | 5846 | Values: 110.0 113.0 111.0 110.0 111.0 112.0 110.0 111.0 110.0 112.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:120.50 stddev:13.88 (11.5%) stddev-sans-first:12.54 09:09:55 INFO - PROCESS | 5846 | Values: 147.0 120.0 119.0 155.0 117.0 117.0 117.0 118.0 132.0 121.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:0.79 (0.5%) stddev-sans-first:0.71 09:09:55 INFO - PROCESS | 5846 | Values: 159.0 161.0 160.0 161.0 161.0 161.0 160.0 160.0 159.0 160.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#3] audio-fft Cycles:10 Average:81.30 Median:75.50 stddev:13.23 (17.5%) stddev-sans-first:13.77 09:09:55 INFO - PROCESS | 5846 | Values: 74.0 76.0 75.0 115.0 75.0 75.0 76.0 78.0 94.0 75.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:83.00 stddev:10.79 (13.0%) stddev-sans-first:11.26 09:09:55 INFO - PROCESS | 5846 | Values: 84.0 83.0 110.0 83.0 83.0 83.0 99.0 83.0 82.0 105.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 09:09:55 INFO - PROCESS | 5846 | Values: 117.0 118.0 117.0 118.0 117.0 117.0 117.0 117.0 116.0 117.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.53 09:09:55 INFO - PROCESS | 5846 | Values: 148.0 147.0 146.0 146.0 147.0 147.0 147.0 147.0 146.0 146.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:09:55 INFO - PROCESS | 5846 | Values: 95.0 96.0 96.0 96.0 95.0 95.0 95.0 96.0 96.0 95.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.50 stddev:0.88 (1.7%) stddev-sans-first:0.87 09:09:55 INFO - PROCESS | 5846 | Values: 51.0 49.0 49.0 51.0 51.0 49.0 50.0 50.0 51.0 50.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:64.00 stddev:0.82 (1.3%) stddev-sans-first:0.83 09:09:55 INFO - PROCESS | 5846 | Values: 63.0 64.0 64.0 64.0 64.0 63.0 62.0 64.0 64.0 65.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:77.00 stddev:1.49 (1.9%) stddev-sans-first:1.51 09:09:55 INFO - PROCESS | 5846 | Values: 76.0 76.0 76.0 80.0 77.0 77.0 77.0 77.0 80.0 77.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:124.50 stddev:11.81 (9.5%) stddev-sans-first:12.33 09:09:55 INFO - PROCESS | 5846 | Values: 121.0 124.0 126.0 121.0 124.0 125.0 121.0 121.0 160.0 127.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.00 Median:161.50 stddev:4.08 (2.5%) stddev-sans-first:4.31 09:09:55 INFO - PROCESS | 5846 | Values: 159.0 161.0 163.0 158.0 164.0 156.0 155.0 167.0 162.0 155.0 09:09:55 INFO - PROCESS | 5846 | 09:09:55 INFO - PROCESS | 5846 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.30 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.60 09:09:55 INFO - PROCESS | 5846 | Values: 62.0 61.0 60.0 60.0 60.0 61.0 59.0 60.0 60.0 60.0 09:09:55 INFO - PROCESS | 5846 | -------- Summary: end -------- 09:09:55 INFO - PROCESS | 5846 | 09:09:56 INFO - TEST-INFO | 5846: exit 0 09:09:56 INFO - TEST-OK | kraken | took 60271ms 09:09:56 INFO - SUITE-END | took 1173s 09:09:56 INFO - Completed test suite (00:19:33) 09:09:56 INFO - javascript benchmark 09:09:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3233.240188555689, 3266.6896644818735, 3280.097973057409, 3292.094673964658, 3301.926873766027, 5411.91882121768, 5436.673799572023, 5441.782328706852, 5447.264619276397, 5467.589930755189, 5512.39363669996, 5527.723613819313, 5539.9169012464845, 5543.223948647183, 5548.223248745179, 4938.259261110827, 4968.552830245278, 5017.84946451607, 5027.472115427864, 5038.496150384951, 4851.611871050308, 4998.225275082368, 5037.370328708911, 5055.69665820052, 5083.847484575469, 4833.250846118857, 4837.709737415748, 4877.268409738532, 4892.290617860408, 4955.975220123894, 4918.803247870081, 4965.0, 4967.180415231483, 4967.403911530621, 4999.850004499871, 4879.682820616648, 4891.8777030574165, 4925.285833554115, 4937.136001199811, 4946.604271658259, 5164.561012313958, 5280.339957505312, 5343.679379237258, 5414.0, 5426.104692725735, 5843.211166492511, 5843.649381037151, 5847.093700476433, 5851.268591426072, 5873.2658417697785, 5844.649321040752, 5885.176075349455, 5890.499307558863, 5898.292204935392, 5909.734061967221, 5322.121849894763, 5377.462253774591, 5442.210879422461, 5493.368262649807, 5504.889902201933, 5968.07494838292, 5991.400859913973, 5993.730282137314, 6017.247844019497, 6029.638221706711, 5780.277465316835, 5781.624194427349, 5788.044972579477, 5794.362620111784, 5799.768009279666, 5671.659700417906, 5717.42825717425, 5757.798477053283, 5803.071508558611, 5877.500412464989, 5679.829605111853, 5691.943080569225, 5731.914021289684, 5745.8563535911935, 5768.134779782982, 5725.4274572543245, 5729.598928075078, 5730.885382292414, 5733.913991290134, 5738.626989245769, 4428.158649856517, 4461.063176732922, 4478.373027776114, 4478.484974228006, 4515.661325400608, 3942.8225729842225, 3965.8017099144927, 3968.3452230381663, 3992.181602771425, 4026.7181297309494, 5543.750531226105, 5548.778048878, 5557.249771280906, 5562.554995600342, 5575.080111781511, 5652.152177173457, 5676.801311954062, 5771.538276937835, 5773.480386765212, 5820.30156381237, 5602.13166959122, 5664.0, 5670.8015219467125, 5673.74468148926, 5697.202391665171, 5011.323471331397, 5035.194368900959, 5040.7731652075745, 5041.798328066836, 5074.3657042869645, 5853.64878107315, 5928.940710592925, 5933.970330148321, 6071.9696401518595, 6075.878482430327, 5781.4507621775865, 5895.469407753323, 5917.556183286357, 5919.141724450017, 5934.465898069022, 6004.42957918997, 6038.245219347582, 6041.78853740117, 6041.939580604314, 6048.153258543719, 5581.5255703265675, 5767.192593037062, 5783.826485205451, 5790.797322093875, 5792.9710351447975, 5704.258446401941, 5754.625949313281, 5761.07822748375, 5772.336181339032, 5850.795222167204, 5662.178984047372, 5701.942980570308, 5709.400512946081, 5710.685912274865, 5710.914336284876, 5683.090705487102, 5694.202811606296, 5695.345035320991, 5700.230468886647, 5700.372958974426, 5486.945130548803, 5598.608097433141, 5663.433656634468, 5690.0, 5715.114157305624, 5702.885942281048, 5706.0, 5711.171880077449, 5713.600047996601, 5743.913841292468, 5609.27079479665, 5615.522680572197, 5621.100623900156, 5623.69069701154, 5635.633683810539], "value": 5377.421058671501, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.9037963546197, 611.8562137897592, 612.6722203621064, 633.8035209085184, 660.4221306356937, 37283.627163728386, 40985.79507102464, 41012.58987410125, 49039.50960490394, 49989.0, 51630.741846290715, 52100.0, 52226.0, 52229.0, 52233.0, 9333.53332333384, 9571.856422153673, 9577.61689532418, 9577.664781732641, 9580.185684216849, 8926.2412694921, 8982.820343593125, 11153.10775137991, 11323.32060076394, 11327.263727857704, 11475.0, 11534.423278836066, 11549.364784936824, 11549.595764148256, 11553.595624153157, 11354.943225283903, 11356.375399353032, 11369.488373023234, 11518.251313664585, 11530.0, 11240.606578769704, 11291.378974156418, 11333.603323883624, 11339.489722962486, 11357.204995650307, 48929.510704892855, 49401.50598494005, 49401.752991235175, 49414.0, 49447.75276123633, 4527.660425468071, 4538.773061346952, 4592.7933243004145, 4668.092088906589, 4683.110209060302, 35898.64101358979, 36213.275734485425, 36475.452868207, 36517.26965460702, 37633.62366376328, 4585.174668559658, 4603.028570007695, 4639.420072490939, 4655.88360290992, 4686.601638860701, 362133.0, 362970.0, 364202.0, 364610.0, 365713.9999999973, 68759.0, 69318.30681693219, 69347.0, 69435.65282173658, 69460.65269673619, 72722.0, 72740.63629681924, 72744.0, 72904.0, 73022.63488682522, 19806.60386792256, 20390.592188156155, 20445.0, 20447.4888127798, 20458.897705511674, 14149.08030977983, 14350.641233969234, 14354.569362919128, 14535.055221410574, 14697.853021469862, 18809.341673041377, 18910.108989101256, 19168.0, 19873.205071797256, 19976.50058748543, 6731.360520750521, 6814.965925170342, 6892.931070689229, 6941.271166527543, 7009.264027277062, 987.3927534566191, 1001.2290536287019, 1002.8395456726888, 1028.0, 1036.1969473657975, 33080.66919330824, 34041.82979085089, 34352.82823585916, 34597.0, 35008.0, 1141.2182654881433, 1182.2788099259444, 1185.3421351150193, 1207.8671346151916, 1223.2049168040844, 17072.743908841378, 18139.90930045341, 18168.727469087975, 18210.271589136286, 18249.63500729978, 533514.0, 537531.0, 539301.0, 540425.0, 540493.0, 67319.66340168366, 67455.3254467449, 67479.66260168668, 67497.0, 67527.0, 1529.3806008566548, 1545.366399776109, 1546.2191593245338, 1573.1426372626945, 1594.378192504924, 32182.19544511406, 33580.328393431504, 33662.49506257457, 33738.83130584331, 33794.15514612154, 55605.0, 57435.138472923776, 57449.712751435974, 57483.42516574948, 57506.7124664374, 407688.0, 409691.0, 409998.0, 410589.0, 410840.0, 40055.79972100121, 41136.0, 42172.36741448942, 43759.562404375545, 45119.09761804811, 47277.76361118173, 53047.0, 53448.73275633597, 53543.73228133834, 53859.19211211757, 43253.783731081145, 43522.697898231985, 45120.0, 46008.769956150005, 46823.53176468192, 44322.778386107864, 44389.0, 44626.77686611676, 45848.0, 46124.538754612026, 44197.55802442063, 44213.557864420945, 44474.3328850061, 44629.330560042275, 44636.10727785361, 37089.25821483609, 38467.80766096152, 38629.0, 39019.0, 39312.41031384589, 28091.43817123687, 28427.801542324407, 28767.0, 28904.42191156123, 29075.273118172216], "value": 22403.555062795698, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.5067473754657, 439.3980247061526, 440.6100600968146, 449.842555105713, 457.54931392578305, 20441.897790511044, 20967.637103588277, 22224.77775222247, 22370.66444003341, 22447.3265802026, 22493.0, 23399.064037438526, 23497.765022349773, 23574.646380304268, 23726.881365593148, 5912.911306330398, 6113.205283313163, 6113.510919126482, 6122.571420000601, 6124.846878828021, 6219.471344935686, 6383.0, 6396.1045453636525, 6397.488200943936, 6403.903941440882, 6190.597611155283, 6308.526860485459, 6346.555741098125, 6361.173047503818, 6388.712507937131, 6297.4017468340435, 6323.841903952392, 6340.682965851712, 6343.984962406016, 6346.809595712136, 5586.469285417878, 5805.7387417566315, 5838.824835254949, 5881.264841894763, 5890.793822216202, 15446.459373921858, 15565.29956151976, 24534.263972080866, 24548.649824259835, 25982.090626828158, 2720.183944816547, 2786.6098746175553, 2796.133198708407, 2807.354308509034, 2808.354078561942, 10660.786784264348, 10971.725706857313, 11078.501467433904, 11599.76800463986, 11683.941580292128, 2029.7462817147857, 2034.52188735647, 2075.2114196605344, 2078.054485209241, 2092.79072092791, 88081.55959220162, 100357.4982125092, 107222.3916641251, 107632.4618376911, 109007.9999999992, 12156.392180390945, 12299.200551964093, 12425.130240883233, 12652.81020784689, 12664.74670506603, 18404.539886502676, 18419.171137298854, 18440.354587589638, 18440.446786596425, 18451.538711532317, 9200.723978280663, 9278.582463789146, 9292.210162136158, 9373.109554592304, 9463.101005404476, 8908.376413651113, 8953.776155596162, 9002.594883230271, 9131.634734610541, 9178.724638260863, 5349.732513374316, 5408.864778380494, 5438.2386465894015, 5462.699551524624, 5518.889622207534, 2179.31351624241, 2185.737711474633, 2198.8131008864107, 2204.845660803728, 2281.178775640768, 923.676713150392, 945.5792172483317, 951.7023513525667, 952.3904700991371, 959.3332633819568, 12983.545575904798, 19009.714854277197, 21487.431102996965, 21896.65019492948, 21921.780782192294, 526.5182358142257, 536.07259441167, 538.9248449343585, 540.227474711159, 540.8755986123214, 6927.480438967098, 7008.26413226614, 7035.472339574552, 7138.964305178441, 7139.92860071403, 651602.0, 678344.0, 752866.0, 761105.0, 791058.0, 112449.43775281071, 116989.41505292589, 117356.0, 118206.40896795462, 118946.0, 534.0680512505702, 540.5702466539111, 542.5903442900664, 542.8724249801294, 546.232543276689, 12202.14584979043, 12350.75298494043, 12376.195547289397, 12607.558735444216, 12635.1787133836, 45781.31328030016, 46568.30147547857, 47219.763901180275, 47862.28206576974, 48603.27095093647, 69050.0, 69740.65129674319, 69753.0, 69975.0, 71155.0, 6569.014647802676, 7685.423593230642, 9505.66730164441, 9594.568244429158, 9857.457839818879, 11000.504977276201, 14459.855401445851, 14550.199739014033, 14812.111273323635, 14814.0, 11949.2830430171, 12341.876581234073, 12381.814272786096, 12487.063470239587, 12807.231566106064, 12282.0, 12349.073819463756, 12477.438515266653, 12543.121981461574, 12800.0, 12260.938695306822, 12486.500539978299, 12582.30797306157, 12625.558105466265, 12732.809007865075, 9843.753906152117, 9862.0, 9967.800643986935, 10089.697309080739, 10247.84628230592, 9495.525223738923, 11799.76400472003, 12449.564265250672, 12496.0, 12870.292133932724], "value": 9610.614918030915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.6991061966364, 1481.733288008158, 1511.833698293187, 1513.4475916290553, 1519.3466809272013, 7626.275503827134, 8290.875636865445, 8618.35362347824, 8622.698205562807, 8884.689035883745, 8636.481811091324, 8638.049814520398, 8681.782955426117, 8686.609102590382, 8687.652493900236, 2086.1968142265187, 2157.6871353653787, 2366.2546297916188, 2413.4811015631635, 2413.8775469406723, 2326.255598208574, 2390.3187591536416, 2394.078279862248, 2406.3984003999, 2407.061246114008, 2365.787079162875, 2378.595638741418, 2383.9761602384015, 2389.354874183969, 2396.7483414241515, 2356.3402247370764, 2358.233574088428, 2370.2415227127326, 2379.060271192869, 2380.3454050136265, 2300.8389412741117, 2337.8714170720605, 2341.695579574661, 2342.4143964009, 2342.941426464352, 8424.578771061422, 8441.873371899426, 8445.493270403735, 8485.448445851061, 8491.108433614443, 5192.7403629818355, 5334.973325133349, 5369.543588794957, 5379.38137114233, 5384.1923711443205, 3296.2583418730833, 3559.94660080099, 3580.1407662160896, 3602.5676918769655, 3604.9279014419826, 1467.889908256885, 1468.177820420568, 1511.2670354877876, 1554.6657468644296, 1563.9765403518957, 11165.776684466266, 11265.436728163559, 11283.210175287733, 11372.943135284271, 11394.943025284903, 4747.255785934059, 4778.832740854123, 4866.440359358719, 4885.511448855086, 4894.437139728905, 9904.900950990543, 9915.702528924145, 9920.4543750093, 9921.950390248003, 9937.45344006072, 5401.864953376118, 5555.50000499957, 5557.527610153102, 5557.638753481011, 5568.470995255444, 4038.5557588665224, 4874.8781280467565, 4958.752062396866, 5055.368078990126, 5087.949120508822, 4159.625633692982, 4181.644560212355, 4226.513950895686, 4283.850065247701, 4286.87139385819, 1384.1694983010113, 1389.173441802126, 1404.676924307415, 1409.2037998530798, 1413.724323756878, 1045.9163266938626, 1046.4715318763976, 1052.321252791945, 1056.6777132974516, 1066.274933045521, 5841.386654401312, 5935.2877654681715, 5959.22530071088, 6013.188219590387, 6096.695165241809, 647.090837373491, 652.9706163222667, 657.4674513643964, 658.1411258307954, 659.1102012283379, 3465.653434656514, 3511.719062474996, 3533.222691007974, 3535.911602209914, 3575.1598374382, 9535.761605959906, 9874.753131171778, 9914.058164474363, 9932.751681208028, 9938.950305248427, 9703.805923881624, 9728.562214700356, 9746.853797193047, 9838.754031149136, 9870.555824987892, 7591.772246832604, 7813.257740514642, 7868.213178682086, 7911.604419779103, 7959.801004974806, 5739.655620662774, 5918.940810592011, 6010.038393856962, 6028.638281703112, 6090.3605121461605, 9399.34204605671, 9474.763130921781, 9497.287703422133, 9510.714678559654, 9608.855867162138, 9387.10822471864, 9764.707058788248, 9785.266105042292, 9796.412215267106, 9803.558839852367, 6841.350071743176, 6933.861322773617, 6943.305669433016, 7470.439717021137, 7615.276548727862, 7735.497192682457, 7792.961035194788, 7890.132085470708, 7992.160823113722, 8000.239977202156, 7390.9260907390235, 7393.0, 7457.067866516685, 7472.626368681435, 7531.472796904164, 7244.094488188976, 7319.707211711472, 7321.084864391951, 7331.670074846751, 7353.558786472829, 6596.80209593713, 6653.301403352578, 6666.400023997669, 6666.733320669057, 6670.433013193933, 4978.477259887804, 5033.245013248042, 5072.898542029213, 5082.491750824888, 5271.762770675252, 5089.465606111305, 5101.566366858859, 5231.738413079407, 5235.60732945023, 5346.625736198429], "value": 4557.419803053841, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.9874903752887, 422.8794793483857, 425.93823895535166, 433.26994015084585, 434.77176975045484, 5392.784288628455, 5398.91901621476, 5403.567714582834, 5484.0, 5521.67479804847, 5143.331366922304, 5349.54528865046, 5362.43694412087, 5376.0, 5395.81114660987, 3961.7028722845757, 4080.5511393746665, 4082.2447847148223, 4145.295299799026, 4241.915161696764, 4151.854685086023, 4196.328587426013, 4197.118605092919, 4198.286291330481, 4198.769067701275, 4167.4790651168605, 4181.602747738975, 4186.790660466964, 4206.621404073633, 4206.684498662597, 4022.6178513041214, 4036.2129384769983, 4136.276151673445, 4167.2082304362375, 4203.726757760735, 3700.834237215269, 3707.461403517537, 3709.2581483703366, 3712.6658600725796, 3713.591504934455, 5211.557017653504, 5231.424543300234, 5240.842774716764, 5257.316548848626, 5269.420363759983, 4727.763611819395, 4829.275608658694, 4843.515648435164, 4875.902481950377, 4886.242632391985, 5613.550915926716, 5624.184493248497, 5631.239782629334, 5634.887302253973, 5643.830685079454, 4135.669146468305, 4136.400221967828, 4150.418941348214, 4172.311568591179, 4185.644220241283, 5703.65778053314, 5765.077587586008, 5778.3531693467585, 5865.166428546729, 5889.411058894119, 4208.978955105266, 4218.514870789899, 4222.725522841006, 4223.184030866695, 4232.767197804151, 4269.359596060615, 4283.464566929134, 4302.913941721211, 4311.116221174651, 4314.827406903752, 4764.809407623657, 4833.661643684979, 4844.806207751651, 4859.2954021666665, 4894.632902532324, 5671.177679236487, 5677.233573467529, 5690.630109042899, 5695.345035320991, 5712.228849105318, 1412.6044707481922, 1421.85070567591, 1422.1467119728288, 1424.7150569886064, 1439.8488158743392, 922.7911436018837, 972.2707969023232, 1007.2294694558668, 1019.1591936652292, 1037.818381783185, 5240.213967904845, 5360.571154307646, 5481.054225749881, 5491.407491827325, 5505.146702261156, 5749.367569567345, 5760.423957604206, 5765.625234359753, 5765.981906332834, 5782.537397008229, 5404.891902161935, 5486.972565137228, 5528.226048353234, 5556.472135147154, 5562.360328562187, 2952.578885314022, 2972.2866512036953, 2983.9407010510954, 2987.7750122449693, 3000.8949686760857, 5775.422457754275, 5821.825345239649, 5831.416858314219, 5839.270091238595, 5854.619449735754, 5453.509184173444, 5709.543236541067, 5718.256626638594, 5719.571032172604, 5721.713914304268, 5763.682997435098, 5807.651540907559, 5826.0, 5831.329397119301, 5851.151583020353, 5346.648097114787, 5469.972650136724, 5598.580106492111, 5617.7191140442, 5629.718514074198, 5586.497215250647, 5591.720413979204, 5606.523445507178, 5614.378725721968, 5641.0, 5844.795432159854, 5849.303701926564, 5875.265591801025, 5875.73559189846, 5877.529797616095, 3974.940375894422, 4070.5929407059057, 4091.324931386288, 4112.403701463331, 4113.354658136769, 5678.432156784289, 5690.2887139107615, 5690.829275121753, 5691.121929763251, 5726.026575482095, 5360.0, 5506.669599824023, 5547.639403438763, 5626.5780066496, 5659.0, 5402.405735369185, 5422.593305502182, 5434.592405569519, 5458.563314934875, 5477.780888764405, 5414.431484694051, 5424.674519528841, 5429.67421954684, 5435.266239057677, 5515.290260019407, 5406.972965135149, 5507.614466987273, 5557.472040156179, 5610.915836262541, 5621.156826476062, 5531.695756733418, 5536.2249285100925, 5538.984236428718, 5540.252065971204, 5540.390557038642], "value": 4309.2330879028705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.359739007489, 818.0347190315433, 828.5650033732289, 899.3794281945455, 899.415380002998, 16316.755248671265, 17845.64308713825, 17917.0, 17930.641387172265, 17934.46196614102, 15423.305951232194, 15506.84493155071, 15545.222738863069, 15567.065976041446, 15587.84412155881, 708.2492557888629, 722.5809030762146, 813.9145389734082, 831.085805613826, 836.7740710008294, 842.2335674536173, 843.9409241353108, 844.9450785698909, 845.3870943565927, 845.6025667936091, 886.7694399456155, 887.0198430734043, 887.8357503861774, 888.2804928008302, 888.4003297774009, 741.3772431157827, 746.6565379925268, 857.0914830279861, 872.1148034744675, 881.4446898453965, 636.150738763751, 642.1555654314561, 642.4507046475289, 642.5116911147514, 643.2184895352123, 16053.438129665406, 16130.032198068153, 16557.91721041399, 16605.08672023038, 16630.916845415817, 15112.924435377752, 15836.445724399706, 15873.444429444913, 16034.919825400915, 16132.59668508285, 12834.229946203164, 12977.286249256287, 12991.740165196738, 14043.0, 14465.566033019026, 4789.760511974422, 4804.5916097131785, 4828.324034635154, 4846.612271018345, 4980.551750342486, 83026.58486707527, 83751.581242094, 84622.57688711517, 84746.57626711827, 84949.57525212396, 12100.878991209975, 12274.754504909852, 12388.132830701945, 12489.687757806127, 12548.686282843002, 22776.0, 23055.42361440944, 23255.651165232535, 23297.650535241984, 23474.530509389715, 8387.748367548984, 8715.172058654418, 8783.429077110095, 8800.075992020746, 8808.251298639687, 4533.54664533544, 4534.070515544306, 4604.263317869125, 4635.930461043087, 4675.579197872208, 3891.105045839473, 3964.3458829292845, 3968.8412463501713, 3969.761814291152, 3977.900552486211, 691.1498856406648, 691.2707094015761, 697.849962258117, 705.463847475922, 705.4814711187273, 830.1781236575868, 846.8899043124478, 868.452874688942, 871.3639043498155, 874.9737507874773, 11429.542818287175, 11507.827382589268, 11792.52829886795, 11938.52245910154, 12292.754144917231, 645.9547831651739, 647.9967600161968, 667.3826710293057, 667.846395329077, 670.5406796344422, 5275.419703832576, 5351.812686555952, 5357.196420536873, 5423.620346575702, 5460.699661518575, 78708.6064569685, 79254.0, 79728.60135699285, 80840.0, 83539.16460835314, 70291.0, 70815.64592177006, 71733.28266717267, 72197.0, 73655.0, 55808.16287755687, 60074.0, 60875.69562152249, 61156.38843611596, 61384.38615613876, 10096.69709908704, 10218.795624087625, 10344.534495947852, 10510.737231569272, 10519.474026298807, 42112.789436052626, 43886.12227755491, 44446.3333050011, 45258.547414525434, 45748.54251457576, 51039.48960510496, 52235.47764522306, 53426.0, 54331.18503222534, 54978.725106374215, 14325.140491570539, 14501.854981450473, 14574.125552466887, 14598.854011459749, 14797.630059248175, 15985.920070399574, 16041.518754437386, 16268.755968660244, 16298.67402651964, 16726.08006559651, 14313.856861431668, 15649.765253520978, 15946.760798587798, 16079.517614471584, 16629.66740665156, 15588.610284742972, 15771.921140394224, 16321.59196020109, 16409.097499637635, 16503.17484125813, 15518.301676424362, 15529.145896975775, 15667.216639168224, 16320.591985200464, 16440.506784796475, 11929.19471453503, 12063.396830158632, 12458.813117803058, 13245.867541324464, 13392.799108013582, 12583.0, 12589.118761686594, 12616.621501354974, 12820.294883781482, 13032.674183145118], "value": 7831.133486810901, "unit": "score"}], "name": "dromaeo_css", "value": 7500.7704955137315, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 111.0, 110.0, 111.0, 112.0, 110.0, 111.0, 110.0, 112.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 120.0, 119.0, 155.0, 117.0, 117.0, 117.0, 118.0, 132.0, 121.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 160.0, 161.0, 161.0, 161.0, 160.0, 160.0, 159.0, 160.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 115.0, 75.0, 75.0, 76.0, 78.0, 94.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 110.0, 83.0, 83.0, 83.0, 99.0, 83.0, 82.0, 105.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 51.0, 51.0, 49.0, 50.0, 50.0, 51.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 64.0, 64.0, 63.0, 62.0, 64.0, 64.0, 65.0], "value": 63.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 80.0, 77.0, 77.0, 77.0, 77.0, 80.0, 77.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 124.0, 126.0, 121.0, 124.0, 125.0, 121.0, 121.0, 160.0, 127.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 163.0, 158.0, 164.0, 156.0, 155.0, 167.0, 162.0, 155.0], "value": 160.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 60.0, 60.0, 61.0, 59.0, 60.0, 60.0, 60.0], "value": 60.3, "unit": "score"}], "name": "kraken", "value": 1465.9999999999998, "alertThreshold": 2.0}]} 09:09:56 INFO - Return code: 0 09:09:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:56 ERROR - # TBPL SUCCESS # 09:09:56 INFO - Running post-action listener: _resource_record_post_action 09:09:56 INFO - [mozharness: 2017-02-09 17:09:56.667412Z] Finished run-tests step (success) 09:09:56 INFO - Running post-run listener: _resource_record_post_run 09:09:56 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 364720128; Read time: 0; Write time: 318544 09:09:56 INFO - TinderboxPrint: CPU usage
13.0% 09:09:56 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:09:56 INFO - TinderboxPrint: I/O write bytes / time
364,720,128 / 318,544 09:09:56 INFO - TinderboxPrint: CPU idle
8,266.3 (87.0%) 09:09:56 INFO - TinderboxPrint: CPU user
1,194.8 (12.6%) 09:09:56 INFO - TinderboxPrint: Swap in / out
0 / 0 09:09:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111034368; Read time: 0; Write time: 112668 09:09:56 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 253300736; Read time: 0; Write time: 205876 09:09:57 INFO - Running post-run listener: _upload_blobber_files 09:09:57 INFO - Blob upload gear active. 09:09:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:09:57 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:58 INFO - (blobuploader) - INFO - Done attempting. 09:09:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:09:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:09:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:59 INFO - (blobuploader) - INFO - Done attempting. 09:09:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:10:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:00 INFO - (blobuploader) - INFO - Done attempting. 09:10:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:10:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:10:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:06 INFO - (blobuploader) - INFO - Done attempting. 09:10:06 INFO - (blobuploader) - INFO - Iteration through files over. 09:10:06 INFO - Return code: 0 09:10:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:10:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:10:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bdb587731a12b267e1b9000dbb9f567759855390886356af48a2b16aec32f69714180bf3d7642db9ddc8f9520abfb53ce8953be580c4e7edc900ad07f99c36be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd097eecf05fba4cb40fdd5e4c16e2b1ecc6902e5af891b909103ccdb628a171423d61339c67eb7f6dba4ba65f3d17f7f840af5acfc37001dfd8d3f137d82d3c", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e061246d63b240da5efede88d64b8377b467ad210fcd788572d1628e4b99f470f912512d6b659f808cec162c97032ea4ecdd72dc35fdb54250f750daca0fad7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15b4614233b26251d264b5759d6377c24ac79f582a52690b5252b25a94262d11c17950ef341739d0f87d5d1d409c8e2a6b3591b65e0da00e080a0b2527ca3c62"} 09:10:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:10:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:10:06 INFO - Contents: 09:10:06 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bdb587731a12b267e1b9000dbb9f567759855390886356af48a2b16aec32f69714180bf3d7642db9ddc8f9520abfb53ce8953be580c4e7edc900ad07f99c36be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd097eecf05fba4cb40fdd5e4c16e2b1ecc6902e5af891b909103ccdb628a171423d61339c67eb7f6dba4ba65f3d17f7f840af5acfc37001dfd8d3f137d82d3c", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e061246d63b240da5efede88d64b8377b467ad210fcd788572d1628e4b99f470f912512d6b659f808cec162c97032ea4ecdd72dc35fdb54250f750daca0fad7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15b4614233b26251d264b5759d6377c24ac79f582a52690b5252b25a94262d11c17950ef341739d0f87d5d1d409c8e2a6b3591b65e0da00e080a0b2527ca3c62"} 09:10:06 INFO - Running post-run listener: copy_logs_to_upload_dir 09:10:06 INFO - Copying logs to upload dir... 09:10:06 INFO - mkdir: /builds/slave/test/build/upload/logs 09:10:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.740111 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-02-09 09:10:05.738920) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 09:10:05.742059) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bdb587731a12b267e1b9000dbb9f567759855390886356af48a2b16aec32f69714180bf3d7642db9ddc8f9520abfb53ce8953be580c4e7edc900ad07f99c36be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd097eecf05fba4cb40fdd5e4c16e2b1ecc6902e5af891b909103ccdb628a171423d61339c67eb7f6dba4ba65f3d17f7f840af5acfc37001dfd8d3f137d82d3c", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e061246d63b240da5efede88d64b8377b467ad210fcd788572d1628e4b99f470f912512d6b659f808cec162c97032ea4ecdd72dc35fdb54250f750daca0fad7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15b4614233b26251d264b5759d6377c24ac79f582a52690b5252b25a94262d11c17950ef341739d0f87d5d1d409c8e2a6b3591b65e0da00e080a0b2527ca3c62"} build_url:https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011161 build_url: 'https://queue.taskcluster.net/v1/task/bxXyMWRFQf-SndmpKA-mVQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bdb587731a12b267e1b9000dbb9f567759855390886356af48a2b16aec32f69714180bf3d7642db9ddc8f9520abfb53ce8953be580c4e7edc900ad07f99c36be", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd097eecf05fba4cb40fdd5e4c16e2b1ecc6902e5af891b909103ccdb628a171423d61339c67eb7f6dba4ba65f3d17f7f840af5acfc37001dfd8d3f137d82d3c", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e061246d63b240da5efede88d64b8377b467ad210fcd788572d1628e4b99f470f912512d6b659f808cec162c97032ea4ecdd72dc35fdb54250f750daca0fad7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15b4614233b26251d264b5759d6377c24ac79f582a52690b5252b25a94262d11c17950ef341739d0f87d5d1d409c8e2a6b3591b65e0da00e080a0b2527ca3c62"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-09 09:10:05.766757) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 09:10:05.767073) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1486658949.915155-1245474371 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-09 09:10:05.848667) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 09:10:05.849013) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-09 09:10:05.849322) ========= ========= Total master_lag: 0.16 =========