builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1490556825.64 results: success (0) revision: 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:45.637087) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:45.637534) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:45.637804) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:45.652389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:45.652665) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-26 12:33:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2017-03-26 12:33:47 (227 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090169 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:45.758554) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:45.758828) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019494 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:45.807692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-26 12:33:45.807994) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e --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=05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-26 12:33:47,924 truncating revision to first 12 chars 2017-03-26 12:33:47,925 Setting DEBUG logging. 2017-03-26 12:33:47,925 attempt 1/10 2017-03-26 12:33:47,925 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1c0b16d1747c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-26 12:33:49,614 unpacking tar archive at: mozilla-central-1c0b16d1747c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.057855 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-26 12:33:47.878978) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:47.879296) ========= script_repo_revision: 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:47.879691) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:47.879975) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-26 12:33:47.888285) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-03-26 12:33:47.888572) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:33:50 INFO - MultiFileLogger online at 20170326 12:33:50 in /builds/slave/test 12:33:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:33:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:33:50 INFO - {'append_to_log': False, 12:33:50 INFO - 'base_work_dir': '/builds/slave/test', 12:33:50 INFO - 'blob_upload_branch': 'Firefox', 12:33:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:33:50 INFO - 'branch': 'Firefox', 12:33:50 INFO - 'buildbot_json_path': 'buildprops.json', 12:33:50 INFO - 'config_files': ('talos/linux_config.py',), 12:33:50 INFO - 'default_actions': ('clobber', 12:33:50 INFO - 'read-buildbot-config', 12:33:50 INFO - 'download-and-extract', 12:33:50 INFO - 'populate-webroot', 12:33:50 INFO - 'create-virtualenv', 12:33:50 INFO - 'install', 12:33:50 INFO - 'run-tests'), 12:33:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:33:50 INFO - 'download_minidump_stackwalk': True, 12:33:50 INFO - 'download_symbols': 'ondemand', 12:33:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:33:50 INFO - 'tooltool.py': '/tools/tooltool.py', 12:33:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:33:50 INFO - '/tools/misc-python/virtualenv.py')}, 12:33:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:33:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:33:50 INFO - 'gecko_profile': False, 12:33:50 INFO - 'gecko_profile_interval': 0, 12:33:50 INFO - 'installer_path': 'installer.exe', 12:33:50 INFO - 'log_level': 'info', 12:33:50 INFO - 'log_name': 'talos', 12:33:50 INFO - 'log_to_console': True, 12:33:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:33:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:33:50 INFO - 'opt_config_files': (), 12:33:50 INFO - 'pip_index': False, 12:33:50 INFO - 'suite': 'dromaeojs', 12:33:50 INFO - 'system_bits': '32', 12:33:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:33:50 INFO - 'title': 'talos-linux64-ix-069', 12:33:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:33:50 INFO - 'use_talos_json': True, 12:33:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:33:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:33:50 INFO - 'work_dir': 'build'} 12:33:50 INFO - [mozharness: 2017-03-26 19:33:50.096974Z] Running clobber step. 12:33:50 INFO - Running pre-action listener: _resource_record_pre_action 12:33:50 INFO - Running main action method: clobber 12:33:50 INFO - rmtree: /builds/slave/test/build 12:33:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:33:51 INFO - Running post-action listener: _resource_record_post_action 12:33:51 INFO - [mozharness: 2017-03-26 19:33:51.027876Z] Finished clobber step (success) 12:33:51 INFO - [mozharness: 2017-03-26 19:33:51.027976Z] Running read-buildbot-config step. 12:33:51 INFO - Running pre-action listener: _resource_record_pre_action 12:33:51 INFO - Running main action method: read_buildbot_config 12:33:51 INFO - Using buildbot properties: 12:33:51 INFO - { 12:33:51 INFO - "project": "", 12:33:51 INFO - "product": "firefox", 12:33:51 INFO - "who": "archaeopteryx@coole-files.de", 12:33:51 INFO - "installer_path": "public/build/target.tar.bz2", 12:33:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 12:33:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:33:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 12:33:51 INFO - "stage_platform": "linux64-pgo", 12:33:51 INFO - "basedir": "/builds/slave/test", 12:33:51 INFO - "buildnumber": 96, 12:33:51 INFO - "platform": "ubuntu64_hw", 12:33:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:33:51 INFO - "slavebuilddir": "test", 12:33:51 INFO - "taskId": "QuNk0kZ-SM2GDfhUYazMjw", 12:33:51 INFO - "branch": "mozilla-central", 12:33:51 INFO - "script_repo_revision": "production", 12:33:51 INFO - "revision": "1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e", 12:33:51 INFO - "slavename": "talos-linux64-ix-069", 12:33:51 INFO - "repo_path": "mozilla-central" 12:33:51 INFO - } 12:33:51 INFO - Finding installer, test and symbols from parent task. 12:33:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QuNk0kZ-SM2GDfhUYazMjw'}, attempt #1 12:33:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QuNk0kZ-SM2GDfhUYazMjw'}, attempt #1 12:33:52 INFO - Task dependencies: FjR3FoP1RZ2AVDaNiiutVQ 12:33:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ'}, attempt #1 12:33:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.tar.bz2 12:33:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/test_packages.json 12:33:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.crashreporter-symbols.zip 12:33:52 INFO - Running post-action listener: _resource_record_post_action 12:33:52 INFO - [mozharness: 2017-03-26 19:33:52.544323Z] Finished read-buildbot-config step (success) 12:33:52 INFO - [mozharness: 2017-03-26 19:33:52.544540Z] Running download-and-extract step. 12:33:52 INFO - Running pre-action listener: _resource_record_pre_action 12:33:52 INFO - Running main action method: download_and_extract 12:33:52 INFO - mkdir: /builds/slave/test/build/tests 12:33:52 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')]} 12:33:52 INFO - trying https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.test_packages.json 12:33:52 INFO - Downloading https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:33:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:33:53 INFO - Downloaded 986 bytes. 12:33:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:33:53 INFO - Using the following test package requirements: 12:33:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:33:53 INFO - u'common': [u'target.common.tests.zip'], 12:33:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:33:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:33:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:33:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:33:53 INFO - u'mozbase': [u'target.common.tests.zip'], 12:33:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:33:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:33:53 INFO - u'web-platform': [u'target.common.tests.zip', 12:33:53 INFO - u'target.web-platform.tests.zip'], 12:33:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:33:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:33:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.common.tests.zip 12:33:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:33:53 INFO - Fetch https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.common.tests.zip into memory 12:33:54 INFO - Expected file size: 60250404 12:33:54 INFO - Obtained file size: 60250404 12:34:04 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:34:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.common.tests.zip 12:34:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:34:04 INFO - Fetch https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.common.tests.zip into memory 12:34:05 INFO - Expected file size: 60250404 12:34:05 INFO - Obtained file size: 60250404 12:34:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.talos.tests.zip 12:34:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:34:16 INFO - Fetch https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.talos.tests.zip into memory 12:34:17 INFO - Expected file size: 14404171 12:34:17 INFO - Obtained file size: 14404171 12:34:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:18 INFO - trying https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.tar.bz2 12:34:18 INFO - Downloading https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:34:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:34:39 INFO - Downloaded 67989184 bytes. 12:34:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.tar.bz2 12:34:39 INFO - mkdir: /builds/slave/test/properties 12:34:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:34:39 INFO - Writing to file /builds/slave/test/properties/build_url 12:34:39 INFO - Contents: 12:34:39 INFO - build_url:https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.tar.bz2 12:34:39 INFO - Running post-action listener: _resource_record_post_action 12:34:39 INFO - Running post-action listener: set_extra_try_arguments 12:34:39 INFO - [mozharness: 2017-03-26 19:34:39.552071Z] Finished download-and-extract step (success) 12:34:39 INFO - [mozharness: 2017-03-26 19:34:39.552297Z] Running populate-webroot step. 12:34:39 INFO - Running pre-action listener: _resource_record_pre_action 12:34:39 INFO - Running main action method: populate_webroot 12:34:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:34:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:34:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:34:39 INFO - '--tppagecycles', 12:34:39 INFO - '7'], 12:34:39 INFO - 'tests': ['tsvgm']}}, 12:34:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:34:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:34:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:34:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:34:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:34:39 INFO - 'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:34:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:34:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:34:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:34:39 INFO - 'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['damp', 'tps']}, 12:34:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:34:39 INFO - 'tests': ['damp', 'tps']}, 12:34:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['dromaeo_dom']}, 12:34:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:34:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['basic_compositor_video']}, 12:34:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:34:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['a11yr', 12:34:39 INFO - 'ts_paint', 12:34:39 INFO - 'tpaint', 12:34:39 INFO - 'sessionrestore', 12:34:39 INFO - 'sessionrestore_no_auto_restore', 12:34:39 INFO - 'tabpaint']}, 12:34:39 INFO - 'other-e10s': {'tests': ['a11yr', 12:34:39 INFO - 'ts_paint', 12:34:39 INFO - 'tpaint', 12:34:39 INFO - 'sessionrestore', 12:34:39 INFO - 'sessionrestore_no_auto_restore', 12:34:39 INFO - 'tabpaint']}, 12:34:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:34:39 INFO - 'ts_paint', 12:34:39 INFO - 'tpaint', 12:34:39 INFO - 'sessionrestore', 12:34:39 INFO - 'sessionrestore_no_auto_restore', 12:34:39 INFO - 'tabpaint']}, 12:34:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:34:39 INFO - 'ts_paint', 12:34:39 INFO - 'tpaint', 12:34:39 INFO - 'sessionrestore', 12:34:39 INFO - 'sessionrestore_no_auto_restore', 12:34:39 INFO - 'tabpaint']}, 12:34:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['a11yr', 12:34:39 INFO - 'ts_paint', 12:34:39 INFO - 'tpaint', 12:34:39 INFO - 'sessionrestore', 12:34:39 INFO - 'sessionrestore_no_auto_restore', 12:34:39 INFO - 'tabpaint']}, 12:34:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['a11yr', 12:34:39 INFO - 'ts_paint', 12:34:39 INFO - 'tpaint', 12:34:39 INFO - 'sessionrestore', 12:34:39 INFO - 'sessionrestore_no_auto_restore', 12:34:39 INFO - 'tabpaint']}, 12:34:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['tsvgx', 12:34:39 INFO - 'tsvgr_opacity', 12:34:39 INFO - 'tart', 12:34:39 INFO - 'tscrollx', 12:34:39 INFO - 'cart', 12:34:39 INFO - 'tsvg_static']}, 12:34:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:34:39 INFO - 'tsvgr_opacity', 12:34:39 INFO - 'tart', 12:34:39 INFO - 'tscrollx', 12:34:39 INFO - 'cart', 12:34:39 INFO - 'tsvg_static']}, 12:34:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:34:39 INFO - 'talos_options': ['--disable-e10s'], 12:34:39 INFO - 'tests': ['tp5o']}, 12:34:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:34:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:34:39 INFO - 'talos_options': ['--disable-e10s', 12:34:39 INFO - '--xperf_path', 12:34:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:34:39 INFO - 'tests': ['tp5n']}, 12:34:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:34:39 INFO - 'talos_options': ['--xperf_path', 12:34:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:34:39 INFO - 'tests': ['tp5n']}}, 12:34:39 INFO - 'talos.zip': {'path': '', 12:34:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:34:39 INFO - Running post-action listener: _resource_record_post_action 12:34:39 INFO - [mozharness: 2017-03-26 19:34:39.568107Z] Finished populate-webroot step (success) 12:34:39 INFO - [mozharness: 2017-03-26 19:34:39.568197Z] Running create-virtualenv step. 12:34:39 INFO - Running pre-action listener: _resource_record_pre_action 12:34:39 INFO - Running main action method: create_virtualenv 12:34:39 INFO - Creating virtualenv /builds/slave/test/build/venv 12:34:39 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 12:34:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:34:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:34:39 INFO - Using real prefix '/usr' 12:34:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:34:40 INFO - Installing distribute.............................................................................................................................................................................................done. 12:34:42 INFO - Installing pip.................done. 12:34:42 INFO - Return code: 0 12:34:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:34:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:34:42 INFO - Reading from file tmpfile_stdout 12:34:42 INFO - Output received: 12:34:42 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) 12:34:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:34:42 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')]} 12:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:42 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')]} 12:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:42 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:34:42 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 12:34:42 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 12:34:42 INFO - Using env: {'DISPLAY': ':0', 12:34:42 INFO - 'HOME': '/home/cltbld', 12:34:42 INFO - 'LANG': 'en_US.UTF-8', 12:34:42 INFO - 'LANGUAGE': 'en_US:en', 12:34:42 INFO - 'LOGNAME': 'cltbld', 12:34:42 INFO - 'MAIL': '/var/mail/cltbld', 12:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:42 INFO - 'MOZ_NO_REMOTE': '1', 12:34:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:42 INFO - 'NO_EM_RESTART': '1', 12:34:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:42 INFO - 'PWD': '/builds/slave/test', 12:34:42 INFO - 'SHELL': '/bin/bash', 12:34:42 INFO - 'SHLVL': '1', 12:34:42 INFO - 'TERM': 'linux', 12:34:42 INFO - 'TMOUT': '86400', 12:34:42 INFO - 'USER': 'cltbld', 12:34:42 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:42 INFO - '_': '/tools/buildbot/bin/python'} 12:34:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:42 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 12:34:42 INFO - Cleaning up... 12:34:42 INFO - Return code: 0 12:34:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:34:42 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')]} 12:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:42 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')]} 12:34:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:42 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:34:42 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 12:34:42 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 12:34:42 INFO - Using env: {'DISPLAY': ':0', 12:34:42 INFO - 'HOME': '/home/cltbld', 12:34:42 INFO - 'LANG': 'en_US.UTF-8', 12:34:42 INFO - 'LANGUAGE': 'en_US:en', 12:34:42 INFO - 'LOGNAME': 'cltbld', 12:34:42 INFO - 'MAIL': '/var/mail/cltbld', 12:34:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:42 INFO - 'MOZ_NO_REMOTE': '1', 12:34:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:42 INFO - 'NO_EM_RESTART': '1', 12:34:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:42 INFO - 'PWD': '/builds/slave/test', 12:34:42 INFO - 'SHELL': '/bin/bash', 12:34:42 INFO - 'SHLVL': '1', 12:34:42 INFO - 'TERM': 'linux', 12:34:42 INFO - 'TMOUT': '86400', 12:34:42 INFO - 'USER': 'cltbld', 12:34:42 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:34:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:42 INFO - '_': '/tools/buildbot/bin/python'} 12:34:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:42 INFO - Downloading/unpacking psutil>=3.1.1 12:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:34:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:34:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:34:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:34:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:34:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:34:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:34:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:34:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:34:44 INFO - Installing collected packages: psutil 12:34:44 INFO - Running setup.py install for psutil 12:34:44 INFO - building 'psutil._psutil_linux' extension 12:34:44 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 12:34:45 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 12:34:45 INFO - building 'psutil._psutil_posix' extension 12:34:45 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 12:34:45 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 12:34:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:34:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:34:45 INFO - Successfully installed psutil 12:34:45 INFO - Cleaning up... 12:34:45 INFO - Return code: 0 12:34:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:34: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')]} 12:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34: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')]} 12:34:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:34: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:34: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 mozsystemmonitor==0.3 12:34:45 INFO - Using env: {'DISPLAY': ':0', 12:34:45 INFO - 'HOME': '/home/cltbld', 12:34:45 INFO - 'LANG': 'en_US.UTF-8', 12:34:45 INFO - 'LANGUAGE': 'en_US:en', 12:34:45 INFO - 'LOGNAME': 'cltbld', 12:34:45 INFO - 'MAIL': '/var/mail/cltbld', 12:34:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:45 INFO - 'MOZ_NO_REMOTE': '1', 12:34:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:45 INFO - 'NO_EM_RESTART': '1', 12:34:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:45 INFO - 'PWD': '/builds/slave/test', 12:34:45 INFO - 'SHELL': '/bin/bash', 12:34:45 INFO - 'SHLVL': '1', 12:34:45 INFO - 'TERM': 'linux', 12:34:45 INFO - 'TMOUT': '86400', 12:34:45 INFO - 'USER': 'cltbld', 12:34:45 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:34:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:45 INFO - '_': '/tools/buildbot/bin/python'} 12:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:34:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:34:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:34:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:34:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:34:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:34:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:34:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:34:47 INFO - Installing collected packages: mozsystemmonitor 12:34:47 INFO - Running setup.py install for mozsystemmonitor 12:34:47 INFO - Successfully installed mozsystemmonitor 12:34:47 INFO - Cleaning up... 12:34:47 INFO - Return code: 0 12:34:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:34:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:47 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:34:47 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 12:34:47 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 12:34:47 INFO - Using env: {'DISPLAY': ':0', 12:34:47 INFO - 'HOME': '/home/cltbld', 12:34:47 INFO - 'LANG': 'en_US.UTF-8', 12:34:47 INFO - 'LANGUAGE': 'en_US:en', 12:34:47 INFO - 'LOGNAME': 'cltbld', 12:34:47 INFO - 'MAIL': '/var/mail/cltbld', 12:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:47 INFO - 'MOZ_NO_REMOTE': '1', 12:34:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:47 INFO - 'NO_EM_RESTART': '1', 12:34:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:47 INFO - 'PWD': '/builds/slave/test', 12:34:47 INFO - 'SHELL': '/bin/bash', 12:34:47 INFO - 'SHLVL': '1', 12:34:47 INFO - 'TERM': 'linux', 12:34:47 INFO - 'TMOUT': '86400', 12:34:47 INFO - 'USER': 'cltbld', 12:34:47 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:47 INFO - '_': '/tools/buildbot/bin/python'} 12:34:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:48 INFO - Downloading/unpacking jsonschema==2.5.1 12:34: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 12:34: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 12:34: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 12:34: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 12:34: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 12:34: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 12:34:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:34:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:34:50 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 12:34:50 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 12:34:50 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 12:34:50 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 12:34:50 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 12:34:50 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 12:34:50 INFO - Downloading functools32-3.2.3-2.tar.gz 12:34:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:34:50 INFO - warning: no files found matching '*.txt' 12:34:50 INFO - no previously-included directories found matching 'build' 12:34:50 INFO - no previously-included directories found matching 'dist' 12:34:50 INFO - no previously-included directories found matching '.git*' 12:34:50 INFO - Installing collected packages: jsonschema, functools32 12:34:50 INFO - Running setup.py install for functools32 12:34:50 INFO - warning: no files found matching '*.txt' 12:34:50 INFO - no previously-included directories found matching 'build' 12:34:50 INFO - no previously-included directories found matching 'dist' 12:34:50 INFO - no previously-included directories found matching '.git*' 12:34:50 INFO - Successfully installed jsonschema functools32 12:34:50 INFO - Cleaning up... 12:34:50 INFO - Return code: 0 12:34:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:50 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:34:50 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 12:34:50 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 12:34:50 INFO - Using env: {'DISPLAY': ':0', 12:34:50 INFO - 'HOME': '/home/cltbld', 12:34:50 INFO - 'LANG': 'en_US.UTF-8', 12:34:50 INFO - 'LANGUAGE': 'en_US:en', 12:34:50 INFO - 'LOGNAME': 'cltbld', 12:34:50 INFO - 'MAIL': '/var/mail/cltbld', 12:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:50 INFO - 'MOZ_NO_REMOTE': '1', 12:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:50 INFO - 'NO_EM_RESTART': '1', 12:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:50 INFO - 'PWD': '/builds/slave/test', 12:34:50 INFO - 'SHELL': '/bin/bash', 12:34:50 INFO - 'SHLVL': '1', 12:34:50 INFO - 'TERM': 'linux', 12:34:50 INFO - 'TMOUT': '86400', 12:34:50 INFO - 'USER': 'cltbld', 12:34:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:50 INFO - '_': '/tools/buildbot/bin/python'} 12:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:34:50 INFO - Cleaning up... 12:34:50 INFO - Return code: 0 12:34:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:50 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:34:50 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 12:34:50 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 12:34:50 INFO - Using env: {'DISPLAY': ':0', 12:34:50 INFO - 'HOME': '/home/cltbld', 12:34:50 INFO - 'LANG': 'en_US.UTF-8', 12:34:50 INFO - 'LANGUAGE': 'en_US:en', 12:34:50 INFO - 'LOGNAME': 'cltbld', 12:34:50 INFO - 'MAIL': '/var/mail/cltbld', 12:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:50 INFO - 'MOZ_NO_REMOTE': '1', 12:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:50 INFO - 'NO_EM_RESTART': '1', 12:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:50 INFO - 'PWD': '/builds/slave/test', 12:34:50 INFO - 'SHELL': '/bin/bash', 12:34:50 INFO - 'SHLVL': '1', 12:34:50 INFO - 'TERM': 'linux', 12:34:50 INFO - 'TMOUT': '86400', 12:34:50 INFO - 'USER': 'cltbld', 12:34:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:50 INFO - '_': '/tools/buildbot/bin/python'} 12:34:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:51 INFO - Downloading/unpacking blobuploader==1.2.4 12:34: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 12:34: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 12:34: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 12:34: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 12:34: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 12:34: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 12:34:54 INFO - Downloading blobuploader-1.2.4.tar.gz 12:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:34:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:34: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 12:34: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 12:34: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 12:34: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 12:34: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 12:34: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 12:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:34:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:34: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 12:34: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 12:34: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 12:34: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 12:34: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 12:34: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 12:34:54 INFO - Downloading docopt-0.6.1.tar.gz 12:34:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:34:54 INFO - Installing collected packages: blobuploader, requests, docopt 12:34:54 INFO - Running setup.py install for blobuploader 12:34:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:34:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:34:54 INFO - Running setup.py install for requests 12:34:55 INFO - Running setup.py install for docopt 12:34:55 INFO - Successfully installed blobuploader requests docopt 12:34:55 INFO - Cleaning up... 12:34:55 INFO - Return code: 0 12:34:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:34:55 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')]} 12:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:55 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')]} 12:34:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:55 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:34:55 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 12:34:55 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 12:34:55 INFO - Using env: {'DISPLAY': ':0', 12:34:55 INFO - 'HOME': '/home/cltbld', 12:34:55 INFO - 'LANG': 'en_US.UTF-8', 12:34:55 INFO - 'LANGUAGE': 'en_US:en', 12:34:55 INFO - 'LOGNAME': 'cltbld', 12:34:55 INFO - 'MAIL': '/var/mail/cltbld', 12:34:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:55 INFO - 'MOZ_NO_REMOTE': '1', 12:34:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:55 INFO - 'NO_EM_RESTART': '1', 12:34:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:55 INFO - 'PWD': '/builds/slave/test', 12:34:55 INFO - 'SHELL': '/bin/bash', 12:34:55 INFO - 'SHLVL': '1', 12:34:55 INFO - 'TERM': 'linux', 12:34:55 INFO - 'TMOUT': '86400', 12:34:55 INFO - 'USER': 'cltbld', 12:34:55 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:34:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:55 INFO - '_': '/tools/buildbot/bin/python'} 12:34:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:34:55 INFO - Running setup.py (path:/tmp/pip-vTfaO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:34:55 INFO - Running setup.py (path:/tmp/pip-6iw4Fe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:34:55 INFO - Running setup.py (path:/tmp/pip-kT2Gas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:34:55 INFO - Running setup.py (path:/tmp/pip-29Vtmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:34:55 INFO - Running setup.py (path:/tmp/pip-vKObKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:34:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:34:56 INFO - Running setup.py (path:/tmp/pip-qtspFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:34:56 INFO - Running setup.py (path:/tmp/pip-3Iswws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:34:56 INFO - Running setup.py (path:/tmp/pip-pxOoFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:34:56 INFO - Running setup.py (path:/tmp/pip-P342xw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:34:56 INFO - Running setup.py (path:/tmp/pip-69ZByW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:34:56 INFO - Running setup.py (path:/tmp/pip-mCNj67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:34:56 INFO - Running setup.py (path:/tmp/pip-ol8e8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:34:56 INFO - Running setup.py (path:/tmp/pip-qGoyX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:34:56 INFO - Running setup.py (path:/tmp/pip-av4buZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:34:56 INFO - Running setup.py (path:/tmp/pip-pniAgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:34:56 INFO - Running setup.py (path:/tmp/pip-FdtNuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:34:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:34:56 INFO - Running setup.py (path:/tmp/pip-EVip3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:34:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:34:56 INFO - Running setup.py install for manifestparser 12:34:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:34:56 INFO - Running setup.py install for mozcrash 12:34:57 INFO - Running setup.py install for mozdebug 12:34:57 INFO - Running setup.py install for mozdevice 12:34:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:34:57 INFO - Running setup.py install for mozfile 12:34:57 INFO - Running setup.py install for mozhttpd 12:34:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:34:57 INFO - Running setup.py install for mozinfo 12:34:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:34:57 INFO - Running setup.py install for mozInstall 12:34:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:34:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:34:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:34:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:34:57 INFO - Running setup.py install for mozleak 12:34:57 INFO - Running setup.py install for mozlog 12:34:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:34:57 INFO - Running setup.py install for moznetwork 12:34:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:34:58 INFO - Running setup.py install for mozprocess 12:34:58 INFO - Running setup.py install for mozprofile 12:34:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:34:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:34:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:34:58 INFO - Running setup.py install for mozrunner 12:34:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:34:58 INFO - Running setup.py install for mozscreenshot 12:34:58 INFO - Running setup.py install for moztest 12:34:58 INFO - Running setup.py install for mozversion 12:34:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:34:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:34:58 INFO - Cleaning up... 12:34:58 INFO - Return code: 0 12:34:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:34:58 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')]} 12:34:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:34:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:58 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')]} 12:34:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:34:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:34:58 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:34:58 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 12:34:58 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 12:34:58 INFO - Using env: {'DISPLAY': ':0', 12:34:58 INFO - 'HOME': '/home/cltbld', 12:34:58 INFO - 'LANG': 'en_US.UTF-8', 12:34:58 INFO - 'LANGUAGE': 'en_US:en', 12:34:58 INFO - 'LOGNAME': 'cltbld', 12:34:58 INFO - 'MAIL': '/var/mail/cltbld', 12:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:34:58 INFO - 'MOZ_NO_REMOTE': '1', 12:34:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:34:58 INFO - 'NO_EM_RESTART': '1', 12:34:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:34:58 INFO - 'PWD': '/builds/slave/test', 12:34:58 INFO - 'SHELL': '/bin/bash', 12:34:58 INFO - 'SHLVL': '1', 12:34:58 INFO - 'TERM': 'linux', 12:34:58 INFO - 'TMOUT': '86400', 12:34:58 INFO - 'USER': 'cltbld', 12:34:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:34:58 INFO - '_': '/tools/buildbot/bin/python'} 12:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:34:58 INFO - Running setup.py (path:/tmp/pip-Gr3Qh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:34:58 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)) 12:34:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:34:58 INFO - Running setup.py (path:/tmp/pip-ieFkll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:34:59 INFO - Running setup.py (path:/tmp/pip-1p31MJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:34:59 INFO - Running setup.py (path:/tmp/pip-4ebZz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:34:59 INFO - Running setup.py (path:/tmp/pip-in6OtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:34:59 INFO - Running setup.py (path:/tmp/pip-jfV0dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:34:59 INFO - Running setup.py (path:/tmp/pip-D9KGUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:34:59 INFO - Running setup.py (path:/tmp/pip-1sIWh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:34:59 INFO - Running setup.py (path:/tmp/pip-P17wGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:34:59 INFO - Running setup.py (path:/tmp/pip-zITZmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:34:59 INFO - Running setup.py (path:/tmp/pip-kcONaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:34:59 INFO - Running setup.py (path:/tmp/pip-Zm9NL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:34:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:34:59 INFO - Running setup.py (path:/tmp/pip-MpupMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:34:59 INFO - Running setup.py (path:/tmp/pip-IsaEr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:34:59 INFO - Running setup.py (path:/tmp/pip-VOTd45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:34:59 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)) 12:34:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:34:59 INFO - Running setup.py (path:/tmp/pip-uz6Lr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:35:00 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)) 12:35:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:35:00 INFO - Running setup.py (path:/tmp/pip-thOjvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 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)) 12:35:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:35:00 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 12:35:00 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 12:35:00 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 12:35:00 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 12:35:00 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 12:35:00 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 12:35:02 INFO - Downloading blessings-1.6.tar.gz 12:35:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:35:02 INFO - Installing collected packages: blessings 12:35:02 INFO - Running setup.py install for blessings 12:35:02 INFO - Successfully installed blessings 12:35:02 INFO - Cleaning up... 12:35:02 INFO - Return code: 0 12:35:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:35:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:02 INFO - Reading from file tmpfile_stdout 12:35:02 INFO - Current package versions: 12:35:02 INFO - argparse == 1.2.1 12:35:02 INFO - blessings == 1.6 12:35:02 INFO - blobuploader == 1.2.4 12:35:02 INFO - docopt == 0.6.1 12:35:02 INFO - functools32 == 3.2.3-2 12:35:02 INFO - jsonschema == 2.5.1 12:35:02 INFO - manifestparser == 1.1 12:35:02 INFO - mozInstall == 1.12 12:35:02 INFO - mozcrash == 1.0 12:35:02 INFO - mozdebug == 0.1 12:35:02 INFO - mozdevice == 0.49 12:35:02 INFO - mozfile == 1.2 12:35:02 INFO - mozhttpd == 0.7 12:35:02 INFO - mozinfo == 0.9 12:35:02 INFO - mozleak == 0.1 12:35:02 INFO - mozlog == 3.4 12:35:02 INFO - moznetwork == 0.27 12:35:02 INFO - mozprocess == 0.25 12:35:02 INFO - mozprofile == 0.28 12:35:02 INFO - mozrunner == 6.13 12:35:02 INFO - mozscreenshot == 0.1 12:35:02 INFO - mozsystemmonitor == 0.3 12:35:02 INFO - moztest == 0.8 12:35:02 INFO - mozversion == 1.4 12:35:02 INFO - psutil == 3.1.1 12:35:02 INFO - requests == 1.2.3 12:35:02 INFO - wsgiref == 0.1.2 12:35:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:02 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:35:02 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 12:35:02 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 12:35:02 INFO - Using env: {'DISPLAY': ':0', 12:35:02 INFO - 'HOME': '/home/cltbld', 12:35:02 INFO - 'LANG': 'en_US.UTF-8', 12:35:02 INFO - 'LANGUAGE': 'en_US:en', 12:35:02 INFO - 'LOGNAME': 'cltbld', 12:35:02 INFO - 'MAIL': '/var/mail/cltbld', 12:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:02 INFO - 'MOZ_NO_REMOTE': '1', 12:35:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:35:02 INFO - 'NO_EM_RESTART': '1', 12:35:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:02 INFO - 'PWD': '/builds/slave/test', 12:35:02 INFO - 'SHELL': '/bin/bash', 12:35:02 INFO - 'SHLVL': '1', 12:35:02 INFO - 'TERM': 'linux', 12:35:02 INFO - 'TMOUT': '86400', 12:35:02 INFO - 'USER': 'cltbld', 12:35:02 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:02 INFO - '_': '/tools/buildbot/bin/python'} 12:35:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 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)) 12:35:02 INFO - Cleaning up... 12:35:02 INFO - Return code: 0 12:35:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:35:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:35:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:35:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:35:02 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 0x25f21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2890310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', '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 12:35:02 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 12:35:02 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 12:35:02 INFO - Using env: {'DISPLAY': ':0', 12:35:02 INFO - 'HOME': '/home/cltbld', 12:35:02 INFO - 'LANG': 'en_US.UTF-8', 12:35:02 INFO - 'LANGUAGE': 'en_US:en', 12:35:02 INFO - 'LOGNAME': 'cltbld', 12:35:02 INFO - 'MAIL': '/var/mail/cltbld', 12:35:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:02 INFO - 'MOZ_NO_REMOTE': '1', 12:35:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:35:02 INFO - 'NO_EM_RESTART': '1', 12:35:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:35:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:02 INFO - 'PWD': '/builds/slave/test', 12:35:02 INFO - 'SHELL': '/bin/bash', 12:35:02 INFO - 'SHLVL': '1', 12:35:02 INFO - 'TERM': 'linux', 12:35:02 INFO - 'TMOUT': '86400', 12:35:02 INFO - 'USER': 'cltbld', 12:35:02 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:35:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:02 INFO - '_': '/tools/buildbot/bin/python'} 12:35:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:35:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:35:03 INFO - Cleaning up... 12:35:03 INFO - Return code: 0 12:35:03 INFO - Running post-action listener: _resource_record_post_action 12:35:03 INFO - Running post-action listener: _start_resource_monitoring 12:35:03 INFO - Starting resource monitoring. 12:35:03 INFO - [mozharness: 2017-03-26 19:35:03.128745Z] Finished create-virtualenv step (success) 12:35:03 INFO - [mozharness: 2017-03-26 19:35:03.129233Z] Running install step. 12:35:03 INFO - Running pre-action listener: _resource_record_pre_action 12:35:03 INFO - Running main action method: install 12:35:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:35:03 INFO - Reading from file tmpfile_stdout 12:35:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:35:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:35:03 INFO - Reading from file tmpfile_stdout 12:35:03 INFO - Output received: 12:35:03 INFO - Usage: mozinstall [options] installer 12:35:03 INFO - Options: 12:35:03 INFO - -h, --help show this help message and exit 12:35:03 INFO - -d DEST, --destination=DEST 12:35:03 INFO - Directory to install application into. [default: 12:35:03 INFO - "/builds/slave/test"] 12:35:03 INFO - --app=APP Application being installed. [default: firefox] 12:35:03 INFO - mkdir: /builds/slave/test/build/application 12:35:03 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'] 12:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:35:20 INFO - Reading from file tmpfile_stdout 12:35:20 INFO - Output received: 12:35:20 INFO - /builds/slave/test/build/application/firefox/firefox 12:35:20 INFO - Running post-action listener: _resource_record_post_action 12:35:20 INFO - [mozharness: 2017-03-26 19:35:20.563510Z] Finished install step (success) 12:35:20 INFO - [mozharness: 2017-03-26 19:35:20.563786Z] Running run-tests step. 12:35:20 INFO - Running pre-action listener: _resource_record_pre_action 12:35:20 INFO - Running main action method: run_tests 12:35:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:35:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:35:20 INFO - Python 2.7.3 12:35:20 INFO - Return code: 0 12:35:20 INFO - grabbing minidump binary from tooltool 12:35:20 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')]} 12:35:20 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 0x288d400>, '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 0x288e3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288e840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:35:20 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 12:35:20 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 12:35:20 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 12:35:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:35:20 INFO - Return code: 0 12:35:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:35:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:35:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:35:20 INFO - ENV: RUST_BACKTRACE is now 1 12:35:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:35:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:35:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 12:35:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 12:35:20 INFO - Using env: {'DISPLAY': ':0', 12:35:20 INFO - 'HOME': '/home/cltbld', 12:35:20 INFO - 'LANG': 'en_US.UTF-8', 12:35:20 INFO - 'LANGUAGE': 'en_US:en', 12:35:20 INFO - 'LOGNAME': 'cltbld', 12:35:20 INFO - 'MAIL': '/var/mail/cltbld', 12:35:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:35:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:35:20 INFO - 'MOZ_NO_REMOTE': '1', 12:35:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:35:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:35:20 INFO - 'NO_EM_RESTART': '1', 12:35:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:35:20 INFO - 'PWD': '/builds/slave/test', 12:35:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:35:20 INFO - 'RUST_BACKTRACE': '1', 12:35:20 INFO - 'SHELL': '/bin/bash', 12:35:20 INFO - 'SHLVL': '1', 12:35:20 INFO - 'TERM': 'linux', 12:35:20 INFO - 'TMOUT': '86400', 12:35:20 INFO - 'USER': 'cltbld', 12:35:20 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693', 12:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:35:20 INFO - '_': '/tools/buildbot/bin/python'} 12:35:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 12:35:20 INFO - mozversion application_buildid: 20170326184632 12:35:20 INFO - mozversion application_changeset: 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e 12:35:20 INFO - mozversion application_display_name: Nightly 12:35:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:35:20 INFO - mozversion application_name: Firefox 12:35:20 INFO - mozversion application_remotingname: firefox 12:35:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:35:20 INFO - mozversion application_vendor: Mozilla 12:35:20 INFO - mozversion application_version: 55.0a1 12:35:20 INFO - mozversion platform_buildid: 20170326184632 12:35:20 INFO - mozversion platform_changeset: 1c0b16d1747c0964577a8ec21fa2b0a17b44ea4e 12:35:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:35:20 INFO - mozversion platform_version: 55.0a1 12:35:20 INFO - using testdate: 1490556920 12:35:20 INFO - actual date: 1490556920 12:35:20 INFO - starting webserver on 'localhost:57939' 12:35:20 INFO - SUITE-START | Running 2 tests 12:35:20 INFO - TEST-START | dromaeo_css 12:35:20 INFO - operating with platform_type : linux_ 12:35:20 INFO - Initialising browser for dromaeo_css test... 12:35:20 INFO - TEST-INFO | started process 27988 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRfZpL2/profile http://localhost:57939/getInfo.html) 12:35:24 INFO - PID 27988 | __metrics Screen width/height:1600/1200 12:35:24 INFO - PID 27988 | colorDepth:24 12:35:24 INFO - PID 27988 | Browser inner width/height: 1024/697 12:35:24 INFO - PID 27988 | __metrics 12:35:25 ERROR - PID 27988 | 1490556925714 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) 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:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924: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 12:35:25 INFO - PID 27988 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:35:25 INFO - PID 27988 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:35:26 INFO - TEST-INFO | 27988: exit 0 12:35:26 INFO - Browser initialized. 12:35:26 INFO - Running cycle 1/1 for dromaeo_css test... 12:35:26 INFO - TEST-INFO | started process 28081 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRfZpL2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:35:38 INFO - PID 28081 | RSS: Main: 145326080 12:35:38 INFO - PID 28081 | 12:38:41 INFO - PID 28081 | Cycle 1(1): loaded http://localhost:57939/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57939/tests/dromaeo/cssquery-ext.html) 12:38:43 INFO - PID 28081 | RSS: Main: 287801344 12:38:43 INFO - PID 28081 | 12:40:28 INFO - PID 28081 | ************************* 12:40:28 INFO - PID 28081 | A coding exception was thrown and uncaught in a Task. 12:40:28 INFO - PID 28081 | 12:40:28 ERROR - PID 28081 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:40:28 INFO - PID 28081 | Full stack: 12:40:28 INFO - PID 28081 | ************************* 12:40:28 INFO - PID 28081 | ************************* 12:40:28 INFO - PID 28081 | A coding exception was thrown and uncaught in a Task. 12:40:28 INFO - PID 28081 | 12:40:28 ERROR - PID 28081 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:40:28 INFO - PID 28081 | Full stack: 12:40:28 INFO - PID 28081 | ************************* 12:40:28 INFO - PID 28081 | ************************* 12:40:28 INFO - PID 28081 | A coding exception was thrown and uncaught in a Task. 12:40:28 INFO - PID 28081 | 12:40:28 ERROR - PID 28081 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:40:28 INFO - PID 28081 | Full stack: 12:40:28 INFO - PID 28081 | ************************* 12:40:28 INFO - PID 28081 | ************************* 12:40:28 INFO - PID 28081 | A coding exception was thrown and uncaught in a Task. 12:40:28 INFO - PID 28081 | 12:40:28 ERROR - PID 28081 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:40:28 INFO - PID 28081 | Full stack: 12:40:28 INFO - PID 28081 | ************************* 12:41:45 INFO - PID 28081 | Cycle 1(1): loaded http://localhost:57939/tests/dromaeo/cssquery-ext.html (next: http://localhost:57939/tests/dromaeo/cssquery-jquery.html) 12:41:46 INFO - PID 28081 | RSS: Main: 232235008 12:41:46 INFO - PID 28081 | 12:44:47 INFO - PID 28081 | Cycle 1(1): loaded http://localhost:57939/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57939/tests/dromaeo/cssquery-mootools.html) 12:44:48 INFO - PID 28081 | RSS: Main: 179826688 12:44:48 INFO - PID 28081 | 12:47:50 INFO - PID 28081 | Cycle 1(1): loaded http://localhost:57939/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57939/tests/dromaeo/cssquery-prototype.html) 12:47:51 INFO - PID 28081 | RSS: Main: 213008384 12:47:51 INFO - PID 28081 | 12:50:53 INFO - PID 28081 | Cycle 1(1): loaded http://localhost:57939/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57939/tests/dromaeo/cssquery-yui.html) 12:50:54 INFO - PID 28081 | RSS: Main: 186789888 12:50:54 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | Cycle 1(1): loaded http://localhost:57939/tests/dromaeo/cssquery-yui.html (next: http://localhost:57939/tests/dromaeo/cssquery-dojo.html) 12:53:57 INFO - PID 28081 | RSS: Main: 273928192 12:53:57 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | __start_tp_report 12:53:57 INFO - PID 28081 | _x_x_mozilla_page_load 12:53:57 INFO - PID 28081 | _x_x_mozilla_page_load_details 12:53:57 INFO - PID 28081 | |i|pagename|runs| 12:53:57 INFO - PID 28081 | |0;dojo.html;3323.418401779689;3323.800571965681;3336.2159892425293;3340.2818394045275;3376.746743994201;5753.424657534244;5798.188253644488;5807.099899515571;5814.563907706923;5841.357450680422;5873.295204575446;5886.6762328072045;5912.408759124085;5928.835974869174;5959;5135.152699804527;5206.297149884755;5245.213218017299;5319.9202011969855;5396.460353964611;5145.3311069560905;5285.947140528584;5306.389765176996;5357.839264822042;5367.46325367464;5164.586833053366;5175.0943584872875;5180.6373553851245;5262.315898933152;5325.121354976424;5214.921776173361;5219.034478621467;5225.895482090375;5289.814856480005;5318.973405132988;5158.484151584811;5220.164773636238;5259.0270799901755;5265.315508983846;5268.446813084647;5668.26312579362;5765.164051212592;5777.162311464856;5796.1595568642315;5804.448577385142;6251.906221406683;6297.937020629781;6299;6303.684815759194;6308.621482711052;6338.58799178052;6353.142325786006;6353.205849268841;6356.841078973016;6357.459615932651;5787.681677507693;5820.534357251409;5873;5877.823665290048;5882.735276912506;6281.560290779693;6439.5170362223025;6463.7091330890225;6500.349965003462;6503.28463868974;6009.639421634716;6044.304904935901;6048.183495228176;6052.092186172109;6195.101710251988;6177.166082578884;6202.317745048132;6274.937250627527;6345.36546345371;6347.047942808524;6290.370962903673;6311.147995020705;6316.084167795644;6318.7472501100365;6319.905201421982;6123.295820980555;6136.041379585766;6136.56222693796;6324.557280990287;6324.588901721373;4794.688345257547;4803.95196048042;4843.1524483216;4843.636727245471;4844.32179494871;4255.468066491689;4258.041940563348;4267.701260911706;4270.0605866709275;4297.527272000078;5796.362400135981;5941.287045554561;5957.225560677084;5960.8807823844145;6009.579329446897;6136.110264011693;6210.254769427697;6266.404691554295;6272.655003974825;6273.905891411632;5822.796202132905;5852.385499522574;5867.325257595346;5872.500837428781;5904.498117660047;5306.7611957462;5311.176767601028;5311.840644780663;5316.6012549058205;5324.094903866352;6377.202849643794;6444.13004244421;6462.773802916875;6466.805995820133;6531.869362612816;6209.751609935552;6257.78097766576;6261.78083767066;6262.373762623702;6278.340774218641;6440.549161558646;6491.4806815455595;6499.5775274607;6505.31694172124;6506.089147519257;6031.547633927561;6088.6042407243385;6122.938770612237;6146.200993870768;6176.81469555914;6286.15136956505;6347.428731414195;6372.840678983062;6376.968115159394;6379.808605741835;6042.154098426312;6130.693465326805;6139.508839292936;6143.017117261216;6152.446279834837;5920.526357891445;5969.910451343147;5975.671338076448;5976.312724036791;5978.312494063239;5962.19510366095;6130.356312587114;6141.393954534099;6144.539159562962;6149.2620885494025;6119.18351070469;6178.691065446799;6184.505239580732;6193.535484838745;6203.16257305258;5979.402059793986;6016.729247183975;6031.366706495754;6045.425684559959;6083.787067452795 12:53:57 INFO - PID 28081 | |1;ext.html;616.593048587932;665.098791138008;678.0981294877814;1149.298927654131;1163.3834067943992;28736.712632873667;35882.82058589707;36525.634743652554;36530.63469365309;36536.08659783507;38519.42220866682;38914.22171556574;38923.6914862237;38934.22131557367;38986.220275594475;6062.060380641008;6079;8035.075966263896;8206.876896846552;8210.384221183405;7831.177726338738;7959.2836644701965;7962.800929976739;7972;7975.561344126071;8072.798180045487;8161.102278749333;8172.387070969671;8195.918040819604;8227.341812654973;8105.918940810576;8144.348452123816;8155.877661835078;8159.143289954588;8166.469179503313;8058.959705201437;8362.289205417546;8375.246227839463;8380.16198380157;8386.454880432751;39407;39637.20725585472;39694;39753.20493590141;39840;3009.623797025372;3009.9247518811985;3018.6377634683977;3020.501617233154;3062.6477955035234;27291.45417091667;27306.86346568274;27782.166535003984;27859.72140278612;28745.28136796576;3630.2376500934834;3646.08847788053;3652.1234903623204;3663.1940972986167;3667.449882517617;207094;207304;207396;208283;208296;48595.271070933966;48600.75699621479;48845.511544884095;49017;49036.26445603319;50874.74562627163;50985.7450712744;51138.232926506134;51176.23235651468;51196;14518.346674399678;14862.702745945237;14892.180930048735;14900.776488352683;14903;9629.085236902483;9750.90249097514;10773.653293338333;11935.820962685566;12007.759844803055;12871.678208044874;13059.192512570244;13091.672708182181;13172.868271317357;13253.337333133304;5138.537531622172;5215;5223.869403264873;5248.396434410091;5307.787688492494;1014.7716763728102;1051.7948999944938;1052.2003277508995;1054.7679510507676;1062.2617280989639;23730;28473.430531389255;29034.709652903202;29570.704292957227;30959.226019349695;1109.1293334732197;1112.3159257056852;1132.2017977325966;1157.120588352841;1159.4666453431516;12976.35118244084;13676.863231367757;13946.58160255194;13953.511627093003;13966.790498142536;280534;281459;281898;281910;282188;54048.48684835473;54236.45763542393;54270.185947210826;54323.18515222275;54385;1390.3395886953615;1454.3528129982267;1454.8763355114722;1468.9853101468848;1478.8816894648617;22463.439773343838;24020.101504621467;24461.755382445946;24615.13847015346;24697.259082227563;39043.41434878422;39149.80425097857;39264.803675981435;39311.60688393194;39353.60646393499;285722;285885;287275;287469;288278;24936;25112.874435627706;25155.119570815667;32356.191095221868;32579.34841303208;33359.83320083384;36130;36248.45627315645;36503.634963651086;36560.81719591385;27995.860020699765;27999;28468.572971405858;28887.277818053877;29168.41663166767;29477.410451791275;30415.847920760254;30816;30918.69081309158;31256.68743312538;34795.82602086973;35399.292014160084;35541.82229088838;35844.28311433704;35910.461343079354;29592.408151837273;29597;29747.702522974494;29930;30048.289396806867;23578.17476388318;23857.403564911016;24280.1501947431;24406.985967204953;24414.877925610257 12:53:57 INFO - PID 28081 | |2;jquery.html;438.23527943738173;439.8900274931267;444.91769022730796;455.7129008724503;461.5615165592687;17902.462926112206;19110.522236944067;19521.804781952178;19653.705194422095;19667.21331146754;21059.36821895342;21517.784822151774;21739.34781956544;22101.778982210173;22384.104635814547;5770.624909380887;6024.638521688713;6025.789097381593;6027.578069535134;6028.728707208186;5803.448672376117;5900.409959004108;5936;5940.019896717036;5943.049112142058;5895.823125306248;5917.082852157922;5919.082542205965;5923.08192230205;5924.081767326072;5828.650280983134;5844.853878653046;5877.235959325303;5877.85305367365;5921.940780592182;5323.095073837419;5366.543843773284;5372.489613486712;5374.596905232084;5375.677459352412;24744.25767226986;25003.87498062516;25036.123735669345;25255.87372063146;25485.490290194277;1988.2245924089473;1997.340877510418;2004.4587961250381;2006.297795771483;2076.885771282579;15872.285747141406;15919.761203581955;16156.515304540882;16178.110203938779;16596.917015414845;1889.3765057531118;1908.3988543608857;1914.3873960332699;1925.422373288008;1958.7061940708866;92712.53643731738;93152;93300.53349733276;93457.53271233669;95246.52376738141;12001.939990299992;12280.447379867748;12317.137800354069;12318.384080795924;12501.312427816558;17460.825391746173;17530.737038944426;17540.298388064588;17540.649187016443;17542.21060052301;8595.914040859512;8810.515421651871;8813.69152079687;8936.731898043068;8973.551322433852;8032;8090.433669643068;8210.712625058095;8273.917260827435;8291.875621865676;7532.962335188289;7600.8479830403085;7812.921870781333;7888.684452621831;7947.5231486111015;2143.657014877643;2225.4547635829326;2259.378670865492;2262.6719125726677;2268.693726346955;1025.6051420203125;1028.228828378716;1037.7561273100816;1053.694428615693;1064.9297456056768;19915;20439.386818395473;20492.282770102975;20511.38465846027;20788.480287992617;1064.8668916385452;1065.6110519660267;1066.4667666166918;1095.4851219926622;1100.7248187953012;10237.846432303522;11038.392888391056;11169.609063682732;11196.160287978271;11217.102631789438;261128;271100;272438;272639;602608;108670;109804;110781.44609276902;111397;115424;1238.9194702344498;1243.3286025546286;1288.9162204456868;1290.1807352331336;1291.3156027305542;19804.00979950968;19892.419364514528;20215.191392344143;20588.794112059288;20700;38140.618593813706;47667.6581598212;48620.513794861596;48886;49160.75419622879;63418;63611.681941589995;63703.3629663716;65197.348026519125;65457.34542654512;8857.867131992896;9197;9283.210927071275;9338.859917101385;9491.288153388385;13408;13454.394552244976;14092.788608171091;14205.431782728576;14543.563693089225;11993.580224692094;12088.516459341528;12397.132200746222;12523;12611;10780.838287425851;10833.29583577065;11183.440827958731;11246.044086252434;11246.718832028937;11980.820287695866;12108.334041627795;12158.270503769802;12472.563460278847;12786.872131278567;9129.45223286605;9142.497162656118;9160.496172710566;9406.905930940604;9501.904980950101;11933.224340417844;12245.326507042199;12299.631011069681;12470.688232794253;12471.5011399543 12:53:57 INFO - PID 28081 | |3;mootools.html;1190;1329.268902103843;1371.6639423341276;1376.023023653206;1393.7282229965153;8938.329625278106;10400.843987340186;10758.569657213711;10817.729556761085;10824.079953203987;10562.630307939224;10645.254832161752;10650.094741946925;10667.733306667318;10684.305520141186;2012.164951545107;2069.9793002070014;2373.489699714565;2392.868392238426;2412.6501657259687;2377.3581133094053;2419.6128619420897;2439.402346425128;2456.631505274205;2458.889349979246;2400.4958958618713;2405.278416475059;2426.9757302427015;2429.1376561320653;2448.179859746978;2340.6254999200046;2366.857988520677;2382.213869423086;2383.1301574925114;2388.7611238876148;2284.97715022851;2299.344686764264;2300.574393737164;2300.6549017647485;2306.8039216666602;10882.836757448644;10935.453227338681;10970.396628185446;11045.83431248532;11052.944735276353;6143.32423433422;6310.400511951358;6319.083732858756;6321;6325.019621958604;3789.5263092113487;3871.361225397806;4087.816048277805;4108.6918481114035;4131.091159944837;1278.6803299175206;1344.354709739321;1358.77580199267;1377.9104358216684;1396.9511067112703;15904.522864314089;15918.920405398014;15976.201189940457;15977.281022354022;15989;5397.676139431646;5608.803691870847;5628.437156284339;5651.237082993744;5667.858303542362;11006;11040.171987100839;11072.948069933344;11083.833742493869;11085.334879907232;5824.621399609096;6002.279726432856;6008.369121242294;6011.519078473799;6014.849628759316;4847.878803029953;5010.3737032870895;5616.550675945915;5869.765209391662;5936.673482958478;4141.991511910263;4166.812493437742;4272.401863739079;4298.763568003727;4339.587739164775;1242.8260043593905;1290;1297.163329652369;1299.791194189394;1301.258282778826;799.8601992161147;857.2327766648939;865.3077537969599;874.2350443362059;875.4353442029983;6166.105914642441;6758.662066896635;6925.930740692528;6937.7571784987285;7085.822854428578;911.2937473458123;942.6653537993914;943.580106852444;947.843630770459;957.7270477913761;4281.314989601711;4363.6072753452345;4418.270985287391;4440.067585807016;4475.373447717322;13993.72012559743;14497.637559061108;14500.709985800437;14501.564953051426;14554.781678274834;12470.81293780594;12910.870891291155;12937.09440339186;14332.928335358465;14337.498187563386;9985.151262142654;10205.897941020643;10222.386656800616;10228.28402011852;10540.894591053991;6308.463780578519;6808.217055038734;6817.795466136024;6842.726290948306;7008.719651213894;11989.700257493632;12113.273203607812;12139.575114870937;12944.935275323563;13032.609021729364;13164.14433061848;13174.934125329311;13227.206367617973;13287.601371958857;13355.332233388486;8861.468311901306;9163.862542062008;9254.305927055364;9257.861132083159;9277.118673725985;9522.476263805556;9616.807663846825;9718.12536871685;9931.602735890772;10290.794184116425;8723.781905452415;8726.476411415335;8729.301655867643;8897.91102088971;8909.821803564022;8384.455010424303;8556.614952327285;8625.525596092275;8712.346574006848;8889.955550222208;7414.962925185339;7466.25337466249;7477.364424023801;7539.32146106853;7569.697212111454;5860.09168578871;5862.443067908542;5983.880322393615;5994.70026498682;6088;6055.091736239599;6065.272167339947;6100.444386681368;6149.538784591084;6207.16203312547 12:53:57 INFO - PID 28081 | |4;prototype.html;397.49716608490246;416.9708120431569;430.32009425108333;433.8568272470084;444.6642784697553;5614.326280846304;5821.53427725781;5838.824835254938;5938.46553810157;5961.791337303195;5609.467100625444;5807.854803629901;5842.444967728059;5851.502622277101;5869.413058694139;4663.720176789404;4665.390440298101;4687.109449204657;4699.38907941967;4700;4549.408576885;4660.1145782301255;4664.603508701764;4673.83641572545;4706.882327941795;4622.838200662977;4629.703682968758;4633.328167415722;4641.582257596816;4646.25659894417;4646.233371493699;4649.767511624405;4656.393544227236;4658.254679251304;4674.298855171752;4055.168690418457;4166.416701661769;4214.936775948363;4258.91482170358;4261.616454519108;5687.431256874321;5707.400722924075;5709.628874123168;5710.885782284331;5718.34239062509;5464.6994415307145;5477.945220547784;5499.3675727291475;5502.972485137589;5520.337559492847;6125.509959203253;6193.566450348478;6212.689365531751;6238.189035425411;6255.562110652256;4693.629716992931;4695.3426520287185;4703.3885594872445;4708;4715.245560710304;6412.487001039905;6479.83800404989;6541.672916354163;6560.639164845979;6588.505862060317;4831.782569784368;4848.5636292733825;4852.272159176081;4857.684250523705;4882.2432522959;4953.7275449849885;4954.232094025432;4965.90068198634;4969.3539839821315;4977.800887964513;5361.544268737201;5411.188321751769;5419.132938729785;5520.254765606672;5525.419830917776;5897.144913987448;6053.303870054912;6208.4722798561725;6225.595336303126;6252.718627661766;1634.5913521619595;1690.2478397113412;1693.7628731977534;1699.4476795041414;1700.3878603702658;1172.0447835014606;1190.3512585640847;1225.6200577820905;1227.7728620205112;1230.3233221728015;5851.180834683148;5893;5906.940930590725;5914.172015917775;5925.933332000282;6322.178116844781;6326.588771729823;6330.715117819709;6334.683265836689;6350.936490635035;5732.684702341411;5839.751695597226;5845.766169353178;5905.1142328650185;5916.5858389913155;3324.9002529923655;3340.916477088044;3345.9718954346354;3346.481295399217;3367.225538126245;6319.6208227506495;6537.34626537352;6603.075569420285;6604.207495100619;6655.43428808547;6517.902231466532;6543.705533251015;6556.114924485133;6568.671566421755;6584.604923704593;6438.7102580384;6569.540132190701;6645.933540664531;6647.567908085959;6679.332066793282;6357.650329231937;6408.455281301141;6413.422791948747;6431;6440.355964403522;6155.169052178077;6343;6348.079528468438;6352.714127864165;6359.109724638462;6654.966725166343;6666.300038496082;6671.132752742113;6684.331566843277;6692.765753198614;4557.612602928655;4567.451905771329;4573.3368661544555;4577.473590537131;4582.633389328779;6359.4640351184;6400.263969643551;6442.2591401989375;6455.61266324022;6467.741290348334;6211.503079753699;6219.595726277777;6232.580442144598;6269.96865015672;6271.843203919939;6044.184035155374;6255.249370075438;6271.749130034747;6277.771391156074;6277.905831412441;6197.81406557804;6221.5333849960525;6222.284437289589;6227;6245.968770156302;6227.00735764551;6230.127782110418;6269.62382257066;6279.874402512016;6287.497000239879;5873.706314684163;6045.909311360245;6048.818535443944;6053.182820319201;6105.3894610540365 12:53:57 INFO - PID 28081 | |5;yui.html;631.8767840271149;653.9901901471476;677.6002158726351;681.4105798484312;682.9282925292844;14059.086159399632;14684.11895286281;14732.70534589309;14748.852511474883;14767.113973161604;14711.852881471183;14836.184009879453;14851.480198193065;14878.628034299121;15562.299696513655;784.1413652050995;799.808046068945;809.1463506001166;836.807534267119;837.6272558711375;838.6687258532876;840.4033136473112;840.9495430274203;841.0412130171609;841.6591280531395;884.8938127424718;885.2298500304744;885.5882014863088;888.2760550151638;888.3293113699148;868.3660927522913;869.3566760597175;877.7191298784392;878.565110270417;881.5900606218107;635.5455849067938;635.7520566978882;641.983950401239;642.0369445831253;644.3588629313816;15323.616909577238;15667.686646267011;15688.137152456611;15751.133687647174;15804.446844360507;14716.264186790617;15086.924565377212;15108.320125594375;15259.923700381538;15540.533783986499;12218.083643726755;12218.144729868913;12342.938285308606;12395.504179832797;12623.558175463906;3048.5427185922067;3066.796319844407;3067.6472205696186;3087.3979573983083;3190.9042728718177;80533.59733201355;81107.5944620279;81193.59403202946;82172.17827821705;83093;10994.395308258123;11029.44852757359;11110.722231944104;11130.610428634958;11147.609833655782;24415.267541973768;24701.876490617433;24753.25740227796;24884;24902.626460602743;7663;7700.191479894643;7765.107012693499;7936.56348900816;8066.072401673765;3703.3148867459604;3740.6446387593137;3744.475773391727;3796.962030379661;3817.217470418613;4180.707350485498;4250.978745106255;4269.423627810268;4273.401723758676;4519.231730605805;841.7222316635496;847.9152084791594;848.6096395658067;854.769212312672;864.0711235421946;612.6018088242589;630.2688880898136;630.621627023782;632.2034236861496;643.9452646525004;10124.240681948882;10656.467176641137;10763.408012559228;10975.50610222542;11000.504977276041;614.6619359352338;640.961542307463;643.2731013954295;646.7606985415332;653.7189008726199;4714.952850471452;5021.548060674557;5088.185890257615;5095.261187127846;5098.745062746848;74883.25116748872;75024.62487687636;75983;78382;78532.21467785363;48845.55211208683;54482.18276725852;60116;66484.66757666277;69790.953135703;53462.19806702903;54291.72854135704;54376;54497.72751136219;54765;10802.945985270024;11183.888161118284;11272.887271127513;11421.31472111676;11541.942290288494;30821.537676934415;31027.534586981667;31112.57761646172;31393.372132557677;31975.360492790478;37920.241595168496;37993.62006379901;38788;39449;39711.40432893451;13863.37614807316;13992.790108148589;14185.432582696576;14204.644883877985;14327.35526901271;15040.699186016438;15125.319360629019;15140.545783626509;15174.772378414553;15735.842641573438;13016.674583135118;13295.800562991757;13412.865871341162;13502.324883755577;13897.374618142007;14199.503017394341;14240.05114272022;14712.632184195052;14746.557603271918;15250.932434729906;13413;13820.516281930084;14085.295735212994;14335.426582936565;14654.413823446943;10782.299150555189;10904.78190436171;11457.541698331974;11475.598354057902;11788.410579470821;12025.338606376734;12292.815607765711;12316.384180791103;12443.813342800046;12473 12:53:57 INFO - PID 28081 | __end_tp_report 12:53:57 INFO - PID 28081 | __start_cc_report 12:53:57 INFO - PID 28081 | _x_x_mozilla_cycle_collect,1526 12:53:57 INFO - PID 28081 | __end_cc_report 12:53:57 INFO - PID 28081 | __startTimestamp1490558037387__endTimestamp 12:53:57 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | ------- Summary: start ------- 12:53:57 INFO - PID 28081 | Number of tests: 6 12:53:57 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | [#0] dojo.html Cycles:165 Average:5799.14 Median:6009.64 stddev:676.06 (11.2%) stddev-sans-first:649.64 12:53:57 INFO - PID 28081 | Values: 3323.4 3323.8 3336.2 3340.3 3376.7 5753.4 5798.2 5807.1 5814.6 5841.4 5873.3 5886.7 5912.4 5928.8 5959.0 5135.2 5206.3 5245.2 5319.9 5396.5 5145.3 5285.9 5306.4 5357.8 5367.5 5164.6 5175.1 5180.6 5262.3 5325.1 5214.9 5219.0 5225.9 5289.8 5319.0 5158.5 5220.2 5259.0 5265.3 5268.4 5668.3 5765.2 5777.2 5796.2 5804.4 6251.9 6297.9 6299.0 6303.7 6308.6 6338.6 6353.1 6353.2 6356.8 6357.5 5787.7 5820.5 5873.0 5877.8 5882.7 6281.6 6439.5 6463.7 6500.3 6503.3 6009.6 6044.3 6048.2 6052.1 6195.1 6177.2 6202.3 6274.9 6345.4 6347.0 6290.4 6311.1 6316.1 6318.7 6319.9 6123.3 6136.0 6136.6 6324.6 6324.6 4794.7 4804.0 4843.2 4843.6 4844.3 4255.5 4258.0 4267.7 4270.1 4297.5 5796.4 5941.3 5957.2 5960.9 6009.6 6136.1 6210.3 6266.4 6272.7 6273.9 5822.8 5852.4 5867.3 5872.5 5904.5 5306.8 5311.2 5311.8 5316.6 5324.1 6377.2 6444.1 6462.8 6466.8 6531.9 6209.8 6257.8 6261.8 6262.4 6278.3 6440.5 6491.5 6499.6 6505.3 6506.1 6031.5 6088.6 6122.9 6146.2 6176.8 6286.2 6347.4 6372.8 6377.0 6379.8 6042.2 6130.7 6139.5 6143.0 6152.4 5920.5 5969.9 5975.7 5976.3 5978.3 5962.2 6130.4 6141.4 6144.5 6149.3 6119.2 6178.7 6184.5 6193.5 6203.2 5979.4 6016.7 6031.4 6045.4 6083.8 12:53:57 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | [#1] ext.html Cycles:180 Average:41224.82 Median:30867.35 stddev:68328.52 (221.4%) stddev-sans-first:68452.17 12:53:57 INFO - PID 28081 | Values: 616.6 665.1 678.1 1149.3 1163.4 28736.7 35882.8 36525.6 36530.6 36536.1 38519.4 38914.2 38923.7 38934.2 38986.2 6062.1 6079.0 8035.1 8206.9 8210.4 7831.2 7959.3 7962.8 7972.0 7975.6 8072.8 8161.1 8172.4 8195.9 8227.3 8105.9 8144.3 8155.9 8159.1 8166.5 8059.0 8362.3 8375.2 8380.2 8386.5 39407.0 39637.2 39694.0 39753.2 39840.0 3009.6 3009.9 3018.6 3020.5 3062.6 27291.5 27306.9 27782.2 27859.7 28745.3 3630.2 3646.1 3652.1 3663.2 3667.4 207094.0 207304.0 207396.0 208283.0 208296.0 48595.3 48600.8 48845.5 49017.0 49036.3 50874.7 50985.7 51138.2 51176.2 51196.0 14518.3 14862.7 14892.2 14900.8 14903.0 9629.1 9750.9 10773.7 11935.8 12007.8 12871.7 13059.2 13091.7 13172.9 13253.3 5138.5 5215.0 5223.9 5248.4 5307.8 1014.8 1051.8 1052.2 1054.8 1062.3 23730.0 28473.4 29034.7 29570.7 30959.2 1109.1 1112.3 1132.2 1157.1 1159.5 12976.4 13676.9 13946.6 13953.5 13966.8 280534.0 281459.0 281898.0 281910.0 282188.0 54048.5 54236.5 54270.2 54323.2 54385.0 1390.3 1454.4 1454.9 1469.0 1478.9 22463.4 24020.1 24461.8 24615.1 24697.3 39043.4 39149.8 39264.8 39311.6 39353.6 285722.0 285885.0 287275.0 287469.0 288278.0 24936.0 25112.9 25155.1 32356.2 32579.3 33359.8 36130.0 36248.5 36503.6 36560.8 27995.9 27999.0 28468.6 28887.3 29168.4 29477.4 30415.8 30816.0 30918.7 31256.7 34795.8 35399.3 35541.8 35844.3 35910.5 29592.4 29597.0 29747.7 29930.0 30048.3 23578.2 23857.4 24280.2 24407.0 24414.9 12:53:57 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | [#2] jquery.html Cycles:180 Average:26649.41 Median:11601.51 stddev:61866.26 (533.3%) stddev-sans-first:62008.51 12:53:57 INFO - PID 28081 | Values: 438.2 439.9 444.9 455.7 461.6 17902.5 19110.5 19521.8 19653.7 19667.2 21059.4 21517.8 21739.3 22101.8 22384.1 5770.6 6024.6 6025.8 6027.6 6028.7 5803.4 5900.4 5936.0 5940.0 5943.0 5895.8 5917.1 5919.1 5923.1 5924.1 5828.7 5844.9 5877.2 5877.9 5921.9 5323.1 5366.5 5372.5 5374.6 5375.7 24744.3 25003.9 25036.1 25255.9 25485.5 1988.2 1997.3 2004.5 2006.3 2076.9 15872.3 15919.8 16156.5 16178.1 16596.9 1889.4 1908.4 1914.4 1925.4 1958.7 92712.5 93152.0 93300.5 93457.5 95246.5 12001.9 12280.4 12317.1 12318.4 12501.3 17460.8 17530.7 17540.3 17540.6 17542.2 8595.9 8810.5 8813.7 8936.7 8973.6 8032.0 8090.4 8210.7 8273.9 8291.9 7533.0 7600.8 7812.9 7888.7 7947.5 2143.7 2225.5 2259.4 2262.7 2268.7 1025.6 1028.2 1037.8 1053.7 1064.9 19915.0 20439.4 20492.3 20511.4 20788.5 1064.9 1065.6 1066.5 1095.5 1100.7 10237.8 11038.4 11169.6 11196.2 11217.1 261128.0 271100.0 272438.0 272639.0 602608.0 108670.0 109804.0 110781.4 111397.0 115424.0 1238.9 1243.3 1288.9 1290.2 1291.3 19804.0 19892.4 20215.2 20588.8 20700.0 38140.6 47667.7 48620.5 48886.0 49160.8 63418.0 63611.7 63703.4 65197.3 65457.3 8857.9 9197.0 9283.2 9338.9 9491.3 13408.0 13454.4 14092.8 14205.4 14543.6 11993.6 12088.5 12397.1 12523.0 12611.0 10780.8 10833.3 11183.4 11246.0 11246.7 11980.8 12108.3 12158.3 12472.6 12786.9 9129.5 9142.5 9160.5 9406.9 9501.9 11933.2 12245.3 12299.6 12470.7 12471.5 12:53:57 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | [#3] mootools.html Cycles:180 Average:6824.94 Median:3979.59 stddev:4234.30 (106.4%) stddev-sans-first:4225.00 12:53:57 INFO - PID 28081 | Values: 1190.0 1329.3 1371.7 1376.0 1393.7 8938.3 10400.8 10758.6 10817.7 10824.1 10562.6 10645.3 10650.1 10667.7 10684.3 2012.2 2070.0 2373.5 2392.9 2412.7 2377.4 2419.6 2439.4 2456.6 2458.9 2400.5 2405.3 2427.0 2429.1 2448.2 2340.6 2366.9 2382.2 2383.1 2388.8 2285.0 2299.3 2300.6 2300.7 2306.8 10882.8 10935.5 10970.4 11045.8 11052.9 6143.3 6310.4 6319.1 6321.0 6325.0 3789.5 3871.4 4087.8 4108.7 4131.1 1278.7 1344.4 1358.8 1377.9 1397.0 15904.5 15918.9 15976.2 15977.3 15989.0 5397.7 5608.8 5628.4 5651.2 5667.9 11006.0 11040.2 11072.9 11083.8 11085.3 5824.6 6002.3 6008.4 6011.5 6014.8 4847.9 5010.4 5616.6 5869.8 5936.7 4142.0 4166.8 4272.4 4298.8 4339.6 1242.8 1290.0 1297.2 1299.8 1301.3 799.9 857.2 865.3 874.2 875.4 6166.1 6758.7 6925.9 6937.8 7085.8 911.3 942.7 943.6 947.8 957.7 4281.3 4363.6 4418.3 4440.1 4475.4 13993.7 14497.6 14500.7 14501.6 14554.8 12470.8 12910.9 12937.1 14332.9 14337.5 9985.2 10205.9 10222.4 10228.3 10540.9 6308.5 6808.2 6817.8 6842.7 7008.7 11989.7 12113.3 12139.6 12944.9 13032.6 13164.1 13174.9 13227.2 13287.6 13355.3 8861.5 9163.9 9254.3 9257.9 9277.1 9522.5 9616.8 9718.1 9931.6 10290.8 8723.8 8726.5 8729.3 8897.9 8909.8 8384.5 8556.6 8625.5 8712.3 8890.0 7415.0 7466.3 7477.4 7539.3 7569.7 5860.1 5862.4 5983.9 5994.7 6088.0 6055.1 6065.3 6100.4 6149.5 6207.2 12:53:57 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | [#4] prototype.html Cycles:180 Average:5285.25 Median:5860.46 stddev:1507.07 (25.7%) stddev-sans-first:1465.96 12:53:57 INFO - PID 28081 | Values: 397.5 417.0 430.3 433.9 444.7 5614.3 5821.5 5838.8 5938.5 5961.8 5609.5 5807.9 5842.4 5851.5 5869.4 4663.7 4665.4 4687.1 4699.4 4700.0 4549.4 4660.1 4664.6 4673.8 4706.9 4622.8 4629.7 4633.3 4641.6 4646.3 4646.2 4649.8 4656.4 4658.3 4674.3 4055.2 4166.4 4214.9 4258.9 4261.6 5687.4 5707.4 5709.6 5710.9 5718.3 5464.7 5477.9 5499.4 5503.0 5520.3 6125.5 6193.6 6212.7 6238.2 6255.6 4693.6 4695.3 4703.4 4708.0 4715.2 6412.5 6479.8 6541.7 6560.6 6588.5 4831.8 4848.6 4852.3 4857.7 4882.2 4953.7 4954.2 4965.9 4969.4 4977.8 5361.5 5411.2 5419.1 5520.3 5525.4 5897.1 6053.3 6208.5 6225.6 6252.7 1634.6 1690.2 1693.8 1699.4 1700.4 1172.0 1190.4 1225.6 1227.8 1230.3 5851.2 5893.0 5906.9 5914.2 5925.9 6322.2 6326.6 6330.7 6334.7 6350.9 5732.7 5839.8 5845.8 5905.1 5916.6 3324.9 3340.9 3346.0 3346.5 3367.2 6319.6 6537.3 6603.1 6604.2 6655.4 6517.9 6543.7 6556.1 6568.7 6584.6 6438.7 6569.5 6645.9 6647.6 6679.3 6357.7 6408.5 6413.4 6431.0 6440.4 6155.2 6343.0 6348.1 6352.7 6359.1 6655.0 6666.3 6671.1 6684.3 6692.8 4557.6 4567.5 4573.3 4577.5 4582.6 6359.5 6400.3 6442.3 6455.6 6467.7 6211.5 6219.6 6232.6 6270.0 6271.8 6044.2 6255.2 6271.7 6277.8 6277.9 6197.8 6221.5 6222.3 6227.0 6246.0 6227.0 6230.1 6269.6 6279.9 6287.5 5873.7 6045.9 6048.8 6053.2 6105.4 12:53:57 INFO - PID 28081 | 12:53:57 INFO - PID 28081 | [#5] yui.html Cycles:180 Average:16633.63 Median:3721.98 stddev:20553.13 (552.2%) stddev-sans-first:20575.66 12:53:57 INFO - PID 28081 | Values: 631.9 654.0 677.6 681.4 682.9 14059.1 14684.1 14732.7 14748.9 14767.1 14711.9 14836.2 14851.5 14878.6 15562.3 784.1 799.8 809.1 836.8 837.6 838.7 840.4 840.9 841.0 841.7 884.9 885.2 885.6 888.3 888.3 868.4 869.4 877.7 878.6 881.6 635.5 635.8 642.0 642.0 644.4 15323.6 15667.7 15688.1 15751.1 15804.4 14716.3 15086.9 15108.3 15259.9 15540.5 12218.1 12218.1 12342.9 12395.5 12623.6 3048.5 3066.8 3067.6 3087.4 3190.9 80533.6 81107.6 81193.6 82172.2 83093.0 10994.4 11029.4 11110.7 11130.6 11147.6 24415.3 24701.9 24753.3 24884.0 24902.6 7663.0 7700.2 7765.1 7936.6 8066.1 3703.3 3740.6 3744.5 3797.0 3817.2 4180.7 4251.0 4269.4 4273.4 4519.2 841.7 847.9 848.6 854.8 864.1 612.6 630.3 630.6 632.2 643.9 10124.2 10656.5 10763.4 10975.5 11000.5 614.7 641.0 643.3 646.8 653.7 4715.0 5021.5 5088.2 5095.3 5098.7 74883.3 75024.6 75983.0 78382.0 78532.2 48845.6 54482.2 60116.0 66484.7 69791.0 53462.2 54291.7 54376.0 54497.7 54765.0 10802.9 11183.9 11272.9 11421.3 11541.9 30821.5 31027.5 31112.6 31393.4 31975.4 37920.2 37993.6 38788.0 39449.0 39711.4 13863.4 13992.8 14185.4 14204.6 14327.4 15040.7 15125.3 15140.5 15174.8 15735.8 13016.7 13295.8 13412.9 13502.3 13897.4 14199.5 14240.1 14712.6 14746.6 15250.9 13413.0 13820.5 14085.3 14335.4 14654.4 10782.3 10904.8 11457.5 11475.6 11788.4 12025.3 12292.8 12316.4 12443.8 12473.0 12:53:57 INFO - PID 28081 | -------- Summary: end -------- 12:53:57 INFO - PID 28081 | 12:53:58 INFO - TEST-INFO | 28081: exit 0 12:53:58 INFO - TEST-OK | dromaeo_css | took 1117380ms 12:53:58 INFO - TEST-START | kraken 12:53:58 INFO - operating with platform_type : linux_ 12:53:58 INFO - Initialising browser for kraken test... 12:53:58 INFO - TEST-INFO | started process 28414 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQmS7Cd/profile http://localhost:57939/getInfo.html) 12:54:01 INFO - PID 28414 | __metrics Screen width/height:1600/1200 12:54:01 INFO - PID 28414 | colorDepth:24 12:54:01 INFO - PID 28414 | Browser inner width/height: 1024/697 12:54:01 INFO - PID 28414 | __metrics 12:54:02 ERROR - PID 28414 | 1490558042878 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) 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:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924: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 12:54:02 INFO - PID 28414 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:54:02 INFO - PID 28414 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:54:03 INFO - TEST-INFO | 28414: exit 0 12:54:03 INFO - Browser initialized. 12:54:03 INFO - Running cycle 1/1 for kraken test... 12:54:03 INFO - TEST-INFO | started process 28506 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQmS7Cd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:54:15 INFO - PID 28506 | RSS: Main: 145117184 12:54:15 INFO - PID 28506 | 12:54:18 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57939/tests/kraken/driver.html?testName=audio-beat-detection) 12:54:19 INFO - PID 28506 | RSS: Main: 200978432 12:54:19 INFO - PID 28506 | 12:54:21 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57939/tests/kraken/driver.html?testName=audio-dft) 12:54:22 INFO - PID 28506 | RSS: Main: 180785152 12:54:22 INFO - PID 28506 | 12:54:25 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57939/tests/kraken/driver.html?testName=audio-fft) 12:54:25 INFO - PID 28506 | RSS: Main: 261545984 12:54:25 INFO - PID 28506 | 12:54:28 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57939/tests/kraken/driver.html?testName=audio-oscillator) 12:54:28 INFO - PID 28506 | RSS: Main: 172535808 12:54:28 INFO - PID 28506 | 12:54:30 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57939/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:54:31 INFO - PID 28506 | RSS: Main: 176513024 12:54:31 INFO - PID 28506 | 12:54:34 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57939/tests/kraken/driver.html?testName=imaging-darkroom) 12:54:34 INFO - PID 28506 | RSS: Main: 215142400 12:54:34 INFO - PID 28506 | 12:54:38 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57939/tests/kraken/driver.html?testName=imaging-desaturate) 12:54:38 INFO - PID 28506 | RSS: Main: 227270656 12:54:38 INFO - PID 28506 | 12:54:41 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57939/tests/kraken/driver.html?testName=json-parse-financial) 12:54:42 INFO - PID 28506 | RSS: Main: 237449216 12:54:42 INFO - PID 28506 | 12:54:43 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57939/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:54:44 INFO - PID 28506 | RSS: Main: 177975296 12:54:44 INFO - PID 28506 | 12:54:46 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57939/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:54:46 INFO - PID 28506 | RSS: Main: 185831424 12:54:46 INFO - PID 28506 | 12:54:48 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57939/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:54:49 INFO - PID 28506 | RSS: Main: 194883584 12:54:49 INFO - PID 28506 | 12:54:52 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57939/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:54:52 INFO - PID 28506 | RSS: Main: 203591680 12:54:52 INFO - PID 28506 | 12:54:55 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57939/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:54:56 INFO - PID 28506 | RSS: Main: 200056832 12:54:56 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | Cycle 1(1): loaded http://localhost:57939/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57939/tests/kraken/driver.html?testName=ai-astar) 12:54:58 INFO - PID 28506 | RSS: Main: 268603392 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | __start_tp_report 12:54:58 INFO - PID 28506 | _x_x_mozilla_page_load 12:54:58 INFO - PID 28506 | _x_x_mozilla_page_load_details 12:54:58 INFO - PID 28506 | |i|pagename|runs| 12:54:58 INFO - PID 28506 | |0;ai-astar;102;108;108;107;109;108;108;108;108;108 12:54:58 INFO - PID 28506 | |1;audio-beat-detection;142;122;117;165;119;118;119;116;142;117 12:54:58 INFO - PID 28506 | |2;audio-dft;163;160;161;162;161;162;161;162;161;162 12:54:58 INFO - PID 28506 | |3;audio-fft;73;74;74;115;78;75;76;74;91;74 12:54:58 INFO - PID 28506 | |4;audio-oscillator;83;83;109;83;84;82;102;83;83;100 12:54:58 INFO - PID 28506 | |5;imaging-gaussian-blur;113;113;112;112;113;113;114;113;113;113 12:54:58 INFO - PID 28506 | |6;imaging-darkroom;146;148;148;147;147;148;147;147;148;147 12:54:58 INFO - PID 28506 | |7;imaging-desaturate;95;94;93;93;94;94;95;94;94;94 12:54:58 INFO - PID 28506 | |8;json-parse-financial;58;57;58;58;60;58;58;58;58;58 12:54:58 INFO - PID 28506 | |9;json-stringify-tinderbox;56;56;55;56;56;55;54;56;55;55 12:54:58 INFO - PID 28506 | |10;stanford-crypto-aes;80;84;87;80;80;81;80;80;81;80 12:54:58 INFO - PID 28506 | |11;stanford-crypto-ccm;126;135;125;129;132;124;126;132;168;128 12:54:58 INFO - PID 28506 | |12;stanford-crypto-pbkdf2;191;180;183;182;180;183;173;191;184;182 12:54:58 INFO - PID 28506 | |13;stanford-crypto-sha256-iterative;65;63;65;65;65;64;62;65;63;63 12:54:58 INFO - PID 28506 | __end_tp_report 12:54:58 INFO - PID 28506 | __start_cc_report 12:54:58 INFO - PID 28506 | _x_x_mozilla_cycle_collect,1747 12:54:58 INFO - PID 28506 | __end_cc_report 12:54:58 INFO - PID 28506 | __startTimestamp1490558098419__endTimestamp 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | ------- Summary: start ------- 12:54:58 INFO - PID 28506 | Number of tests: 14 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#0] ai-astar Cycles:10 Average:107.40 Median:108.00 stddev:1.96 (1.8%) stddev-sans-first:0.50 12:54:58 INFO - PID 28506 | Values: 102.0 108.0 108.0 107.0 109.0 108.0 108.0 108.0 108.0 108.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:120.50 stddev:16.48 (13.7%) stddev-sans-first:16.65 12:54:58 INFO - PID 28506 | Values: 142.0 122.0 117.0 165.0 119.0 118.0 119.0 116.0 142.0 117.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.71 12:54:58 INFO - PID 28506 | Values: 163.0 160.0 161.0 162.0 161.0 162.0 161.0 162.0 161.0 162.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#3] audio-fft Cycles:10 Average:80.40 Median:74.50 stddev:13.26 (17.8%) stddev-sans-first:13.79 12:54:58 INFO - PID 28506 | Values: 73.0 74.0 74.0 115.0 78.0 75.0 76.0 74.0 91.0 74.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:83.00 stddev:10.24 (12.3%) stddev-sans-first:10.61 12:54:58 INFO - PID 28506 | Values: 83.0 83.0 109.0 83.0 84.0 82.0 102.0 83.0 83.0 100.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 12:54:58 INFO - PID 28506 | Values: 113.0 113.0 112.0 112.0 113.0 113.0 114.0 113.0 113.0 113.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.50 stddev:0.67 (0.5%) stddev-sans-first:0.53 12:54:58 INFO - PID 28506 | Values: 146.0 148.0 148.0 147.0 147.0 148.0 147.0 147.0 148.0 147.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 12:54:58 INFO - PID 28506 | Values: 95.0 94.0 93.0 93.0 94.0 94.0 95.0 94.0 94.0 94.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:58.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 12:54:58 INFO - PID 28506 | Values: 58.0 57.0 58.0 58.0 60.0 58.0 58.0 58.0 58.0 58.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#9] json-stringify-tinderbox Cycles:10 Average:55.40 Median:56.00 stddev:0.70 (1.2%) stddev-sans-first:0.71 12:54:58 INFO - PID 28506 | Values: 56.0 56.0 55.0 56.0 56.0 55.0 54.0 56.0 55.0 55.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:80.50 stddev:2.36 (2.9%) stddev-sans-first:2.46 12:54:58 INFO - PID 28506 | Values: 80.0 84.0 87.0 80.0 80.0 81.0 80.0 80.0 81.0 80.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#11] stanford-crypto-ccm Cycles:10 Average:132.50 Median:130.50 stddev:12.96 (9.9%) stddev-sans-first:13.53 12:54:58 INFO - PID 28506 | Values: 126.0 135.0 125.0 129.0 132.0 124.0 126.0 132.0 168.0 128.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:183.00 stddev:5.26 (2.9%) stddev-sans-first:4.69 12:54:58 INFO - PID 28506 | Values: 191.0 180.0 183.0 182.0 180.0 183.0 173.0 191.0 184.0 182.0 12:54:58 INFO - PID 28506 | 12:54:58 INFO - PID 28506 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:65.00 stddev:1.15 (1.8%) stddev-sans-first:1.17 12:54:58 INFO - PID 28506 | Values: 65.0 63.0 65.0 65.0 65.0 64.0 62.0 65.0 63.0 63.0 12:54:58 INFO - PID 28506 | -------- Summary: end -------- 12:54:58 INFO - PID 28506 | 12:54:59 INFO - TEST-INFO | 28506: exit 0 12:54:59 INFO - TEST-OK | kraken | took 61256ms 12:54:59 INFO - SUITE-END | took 1178s 12:54:59 INFO - Completed test suite (00:19:38) 12:54:59 INFO - javascript benchmark 12:54:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7480.082070165017, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.418401779689, 3323.800571965681, 3336.2159892425293, 3340.2818394045275, 3376.746743994201, 5753.424657534244, 5798.188253644488, 5807.099899515571, 5814.563907706923, 5841.357450680422, 5873.295204575446, 5886.6762328072045, 5912.408759124085, 5928.835974869174, 5959.0, 5135.152699804527, 5206.297149884755, 5245.213218017299, 5319.9202011969855, 5396.460353964611, 5145.3311069560905, 5285.947140528584, 5306.389765176996, 5357.839264822042, 5367.46325367464, 5164.586833053366, 5175.0943584872875, 5180.6373553851245, 5262.315898933152, 5325.121354976424, 5214.921776173361, 5219.034478621467, 5225.895482090375, 5289.814856480005, 5318.973405132988, 5158.484151584811, 5220.164773636238, 5259.0270799901755, 5265.315508983846, 5268.446813084647, 5668.26312579362, 5765.164051212592, 5777.162311464856, 5796.1595568642315, 5804.448577385142, 6251.906221406683, 6297.937020629781, 6299.0, 6303.684815759194, 6308.621482711052, 6338.58799178052, 6353.142325786006, 6353.205849268841, 6356.841078973016, 6357.459615932651, 5787.681677507693, 5820.534357251409, 5873.0, 5877.823665290048, 5882.735276912506, 6281.560290779693, 6439.5170362223025, 6463.7091330890225, 6500.349965003462, 6503.28463868974, 6009.639421634716, 6044.304904935901, 6048.183495228176, 6052.092186172109, 6195.101710251988, 6177.166082578884, 6202.317745048132, 6274.937250627527, 6345.36546345371, 6347.047942808524, 6290.370962903673, 6311.147995020705, 6316.084167795644, 6318.7472501100365, 6319.905201421982, 6123.295820980555, 6136.041379585766, 6136.56222693796, 6324.557280990287, 6324.588901721373, 4794.688345257547, 4803.95196048042, 4843.1524483216, 4843.636727245471, 4844.32179494871, 4255.468066491689, 4258.041940563348, 4267.701260911706, 4270.0605866709275, 4297.527272000078, 5796.362400135981, 5941.287045554561, 5957.225560677084, 5960.8807823844145, 6009.579329446897, 6136.110264011693, 6210.254769427697, 6266.404691554295, 6272.655003974825, 6273.905891411632, 5822.796202132905, 5852.385499522574, 5867.325257595346, 5872.500837428781, 5904.498117660047, 5306.7611957462, 5311.176767601028, 5311.840644780663, 5316.6012549058205, 5324.094903866352, 6377.202849643794, 6444.13004244421, 6462.773802916875, 6466.805995820133, 6531.869362612816, 6209.751609935552, 6257.78097766576, 6261.78083767066, 6262.373762623702, 6278.340774218641, 6440.549161558646, 6491.4806815455595, 6499.5775274607, 6505.31694172124, 6506.089147519257, 6031.547633927561, 6088.6042407243385, 6122.938770612237, 6146.200993870768, 6176.81469555914, 6286.15136956505, 6347.428731414195, 6372.840678983062, 6376.968115159394, 6379.808605741835, 6042.154098426312, 6130.693465326805, 6139.508839292936, 6143.017117261216, 6152.446279834837, 5920.526357891445, 5969.910451343147, 5975.671338076448, 5976.312724036791, 5978.312494063239, 5962.19510366095, 6130.356312587114, 6141.393954534099, 6144.539159562962, 6149.2620885494025, 6119.18351070469, 6178.691065446799, 6184.505239580732, 6193.535484838745, 6203.16257305258, 5979.402059793986, 6016.729247183975, 6031.366706495754, 6045.425684559959, 6083.787067452795], "value": 5752.342115345351, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.593048587932, 665.098791138008, 678.0981294877814, 1149.298927654131, 1163.3834067943992, 28736.712632873667, 35882.82058589707, 36525.634743652554, 36530.63469365309, 36536.08659783507, 38519.42220866682, 38914.22171556574, 38923.6914862237, 38934.22131557367, 38986.220275594475, 6062.060380641008, 6079.0, 8035.075966263896, 8206.876896846552, 8210.384221183405, 7831.177726338738, 7959.2836644701965, 7962.800929976739, 7972.0, 7975.561344126071, 8072.798180045487, 8161.102278749333, 8172.387070969671, 8195.918040819604, 8227.341812654973, 8105.918940810576, 8144.348452123816, 8155.877661835078, 8159.143289954588, 8166.469179503313, 8058.959705201437, 8362.289205417546, 8375.246227839463, 8380.16198380157, 8386.454880432751, 39407.0, 39637.20725585472, 39694.0, 39753.20493590141, 39840.0, 3009.623797025372, 3009.9247518811985, 3018.6377634683977, 3020.501617233154, 3062.6477955035234, 27291.45417091667, 27306.86346568274, 27782.166535003984, 27859.72140278612, 28745.28136796576, 3630.2376500934834, 3646.08847788053, 3652.1234903623204, 3663.1940972986167, 3667.449882517617, 207094.0, 207304.0, 207396.0, 208283.0, 208296.0, 48595.271070933966, 48600.75699621479, 48845.511544884095, 49017.0, 49036.26445603319, 50874.74562627163, 50985.7450712744, 51138.232926506134, 51176.23235651468, 51196.0, 14518.346674399678, 14862.702745945237, 14892.180930048735, 14900.776488352683, 14903.0, 9629.085236902483, 9750.90249097514, 10773.653293338333, 11935.820962685566, 12007.759844803055, 12871.678208044874, 13059.192512570244, 13091.672708182181, 13172.868271317357, 13253.337333133304, 5138.537531622172, 5215.0, 5223.869403264873, 5248.396434410091, 5307.787688492494, 1014.7716763728102, 1051.7948999944938, 1052.2003277508995, 1054.7679510507676, 1062.2617280989639, 23730.0, 28473.430531389255, 29034.709652903202, 29570.704292957227, 30959.226019349695, 1109.1293334732197, 1112.3159257056852, 1132.2017977325966, 1157.120588352841, 1159.4666453431516, 12976.35118244084, 13676.863231367757, 13946.58160255194, 13953.511627093003, 13966.790498142536, 280534.0, 281459.0, 281898.0, 281910.0, 282188.0, 54048.48684835473, 54236.45763542393, 54270.185947210826, 54323.18515222275, 54385.0, 1390.3395886953615, 1454.3528129982267, 1454.8763355114722, 1468.9853101468848, 1478.8816894648617, 22463.439773343838, 24020.101504621467, 24461.755382445946, 24615.13847015346, 24697.259082227563, 39043.41434878422, 39149.80425097857, 39264.803675981435, 39311.60688393194, 39353.60646393499, 285722.0, 285885.0, 287275.0, 287469.0, 288278.0, 24936.0, 25112.874435627706, 25155.119570815667, 32356.191095221868, 32579.34841303208, 33359.83320083384, 36130.0, 36248.45627315645, 36503.634963651086, 36560.81719591385, 27995.860020699765, 27999.0, 28468.572971405858, 28887.277818053877, 29168.41663166767, 29477.410451791275, 30415.847920760254, 30816.0, 30918.69081309158, 31256.68743312538, 34795.82602086973, 35399.292014160084, 35541.82229088838, 35844.28311433704, 35910.461343079354, 29592.408151837273, 29597.0, 29747.702522974494, 29930.0, 30048.289396806867, 23578.17476388318, 23857.403564911016, 24280.1501947431, 24406.985967204953, 24414.877925610257], "value": 16897.013571051015, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.23527943738173, 439.8900274931267, 444.91769022730796, 455.7129008724503, 461.5615165592687, 17902.462926112206, 19110.522236944067, 19521.804781952178, 19653.705194422095, 19667.21331146754, 21059.36821895342, 21517.784822151774, 21739.34781956544, 22101.778982210173, 22384.104635814547, 5770.624909380887, 6024.638521688713, 6025.789097381593, 6027.578069535134, 6028.728707208186, 5803.448672376117, 5900.409959004108, 5936.0, 5940.019896717036, 5943.049112142058, 5895.823125306248, 5917.082852157922, 5919.082542205965, 5923.08192230205, 5924.081767326072, 5828.650280983134, 5844.853878653046, 5877.235959325303, 5877.85305367365, 5921.940780592182, 5323.095073837419, 5366.543843773284, 5372.489613486712, 5374.596905232084, 5375.677459352412, 24744.25767226986, 25003.87498062516, 25036.123735669345, 25255.87372063146, 25485.490290194277, 1988.2245924089473, 1997.340877510418, 2004.4587961250381, 2006.297795771483, 2076.885771282579, 15872.285747141406, 15919.761203581955, 16156.515304540882, 16178.110203938779, 16596.917015414845, 1889.3765057531118, 1908.3988543608857, 1914.3873960332699, 1925.422373288008, 1958.7061940708866, 92712.53643731738, 93152.0, 93300.53349733276, 93457.53271233669, 95246.52376738141, 12001.939990299992, 12280.447379867748, 12317.137800354069, 12318.384080795924, 12501.312427816558, 17460.825391746173, 17530.737038944426, 17540.298388064588, 17540.649187016443, 17542.21060052301, 8595.914040859512, 8810.515421651871, 8813.69152079687, 8936.731898043068, 8973.551322433852, 8032.0, 8090.433669643068, 8210.712625058095, 8273.917260827435, 8291.875621865676, 7532.962335188289, 7600.8479830403085, 7812.921870781333, 7888.684452621831, 7947.5231486111015, 2143.657014877643, 2225.4547635829326, 2259.378670865492, 2262.6719125726677, 2268.693726346955, 1025.6051420203125, 1028.228828378716, 1037.7561273100816, 1053.694428615693, 1064.9297456056768, 19915.0, 20439.386818395473, 20492.282770102975, 20511.38465846027, 20788.480287992617, 1064.8668916385452, 1065.6110519660267, 1066.4667666166918, 1095.4851219926622, 1100.7248187953012, 10237.846432303522, 11038.392888391056, 11169.609063682732, 11196.160287978271, 11217.102631789438, 261128.0, 271100.0, 272438.0, 272639.0, 602608.0, 108670.0, 109804.0, 110781.44609276902, 111397.0, 115424.0, 1238.9194702344498, 1243.3286025546286, 1288.9162204456868, 1290.1807352331336, 1291.3156027305542, 19804.00979950968, 19892.419364514528, 20215.191392344143, 20588.794112059288, 20700.0, 38140.618593813706, 47667.6581598212, 48620.513794861596, 48886.0, 49160.75419622879, 63418.0, 63611.681941589995, 63703.3629663716, 65197.348026519125, 65457.34542654512, 8857.867131992896, 9197.0, 9283.210927071275, 9338.859917101385, 9491.288153388385, 13408.0, 13454.394552244976, 14092.788608171091, 14205.431782728576, 14543.563693089225, 11993.580224692094, 12088.516459341528, 12397.132200746222, 12523.0, 12611.0, 10780.838287425851, 10833.29583577065, 11183.440827958731, 11246.044086252434, 11246.718832028937, 11980.820287695866, 12108.334041627795, 12158.270503769802, 12472.563460278847, 12786.872131278567, 9129.45223286605, 9142.497162656118, 9160.496172710566, 9406.905930940604, 9501.904980950101, 11933.224340417844, 12245.326507042199, 12299.631011069681, 12470.688232794253, 12471.5011399543], "value": 9960.017373764826, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1190.0, 1329.268902103843, 1371.6639423341276, 1376.023023653206, 1393.7282229965153, 8938.329625278106, 10400.843987340186, 10758.569657213711, 10817.729556761085, 10824.079953203987, 10562.630307939224, 10645.254832161752, 10650.094741946925, 10667.733306667318, 10684.305520141186, 2012.164951545107, 2069.9793002070014, 2373.489699714565, 2392.868392238426, 2412.6501657259687, 2377.3581133094053, 2419.6128619420897, 2439.402346425128, 2456.631505274205, 2458.889349979246, 2400.4958958618713, 2405.278416475059, 2426.9757302427015, 2429.1376561320653, 2448.179859746978, 2340.6254999200046, 2366.857988520677, 2382.213869423086, 2383.1301574925114, 2388.7611238876148, 2284.97715022851, 2299.344686764264, 2300.574393737164, 2300.6549017647485, 2306.8039216666602, 10882.836757448644, 10935.453227338681, 10970.396628185446, 11045.83431248532, 11052.944735276353, 6143.32423433422, 6310.400511951358, 6319.083732858756, 6321.0, 6325.019621958604, 3789.5263092113487, 3871.361225397806, 4087.816048277805, 4108.6918481114035, 4131.091159944837, 1278.6803299175206, 1344.354709739321, 1358.77580199267, 1377.9104358216684, 1396.9511067112703, 15904.522864314089, 15918.920405398014, 15976.201189940457, 15977.281022354022, 15989.0, 5397.676139431646, 5608.803691870847, 5628.437156284339, 5651.237082993744, 5667.858303542362, 11006.0, 11040.171987100839, 11072.948069933344, 11083.833742493869, 11085.334879907232, 5824.621399609096, 6002.279726432856, 6008.369121242294, 6011.519078473799, 6014.849628759316, 4847.878803029953, 5010.3737032870895, 5616.550675945915, 5869.765209391662, 5936.673482958478, 4141.991511910263, 4166.812493437742, 4272.401863739079, 4298.763568003727, 4339.587739164775, 1242.8260043593905, 1290.0, 1297.163329652369, 1299.791194189394, 1301.258282778826, 799.8601992161147, 857.2327766648939, 865.3077537969599, 874.2350443362059, 875.4353442029983, 6166.105914642441, 6758.662066896635, 6925.930740692528, 6937.7571784987285, 7085.822854428578, 911.2937473458123, 942.6653537993914, 943.580106852444, 947.843630770459, 957.7270477913761, 4281.314989601711, 4363.6072753452345, 4418.270985287391, 4440.067585807016, 4475.373447717322, 13993.72012559743, 14497.637559061108, 14500.709985800437, 14501.564953051426, 14554.781678274834, 12470.81293780594, 12910.870891291155, 12937.09440339186, 14332.928335358465, 14337.498187563386, 9985.151262142654, 10205.897941020643, 10222.386656800616, 10228.28402011852, 10540.894591053991, 6308.463780578519, 6808.217055038734, 6817.795466136024, 6842.726290948306, 7008.719651213894, 11989.700257493632, 12113.273203607812, 12139.575114870937, 12944.935275323563, 13032.609021729364, 13164.14433061848, 13174.934125329311, 13227.206367617973, 13287.601371958857, 13355.332233388486, 8861.468311901306, 9163.862542062008, 9254.305927055364, 9257.861132083159, 9277.118673725985, 9522.476263805556, 9616.807663846825, 9718.12536871685, 9931.602735890772, 10290.794184116425, 8723.781905452415, 8726.476411415335, 8729.301655867643, 8897.91102088971, 8909.821803564022, 8384.455010424303, 8556.614952327285, 8625.525596092275, 8712.346574006848, 8889.955550222208, 7414.962925185339, 7466.25337466249, 7477.364424023801, 7539.32146106853, 7569.697212111454, 5860.09168578871, 5862.443067908542, 5983.880322393615, 5994.70026498682, 6088.0, 6055.091736239599, 6065.272167339947, 6100.444386681368, 6149.538784591084, 6207.16203312547], "value": 5219.463459380506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.49716608490246, 416.9708120431569, 430.32009425108333, 433.8568272470084, 444.6642784697553, 5614.326280846304, 5821.53427725781, 5838.824835254938, 5938.46553810157, 5961.791337303195, 5609.467100625444, 5807.854803629901, 5842.444967728059, 5851.502622277101, 5869.413058694139, 4663.720176789404, 4665.390440298101, 4687.109449204657, 4699.38907941967, 4700.0, 4549.408576885, 4660.1145782301255, 4664.603508701764, 4673.83641572545, 4706.882327941795, 4622.838200662977, 4629.703682968758, 4633.328167415722, 4641.582257596816, 4646.25659894417, 4646.233371493699, 4649.767511624405, 4656.393544227236, 4658.254679251304, 4674.298855171752, 4055.168690418457, 4166.416701661769, 4214.936775948363, 4258.91482170358, 4261.616454519108, 5687.431256874321, 5707.400722924075, 5709.628874123168, 5710.885782284331, 5718.34239062509, 5464.6994415307145, 5477.945220547784, 5499.3675727291475, 5502.972485137589, 5520.337559492847, 6125.509959203253, 6193.566450348478, 6212.689365531751, 6238.189035425411, 6255.562110652256, 4693.629716992931, 4695.3426520287185, 4703.3885594872445, 4708.0, 4715.245560710304, 6412.487001039905, 6479.83800404989, 6541.672916354163, 6560.639164845979, 6588.505862060317, 4831.782569784368, 4848.5636292733825, 4852.272159176081, 4857.684250523705, 4882.2432522959, 4953.7275449849885, 4954.232094025432, 4965.90068198634, 4969.3539839821315, 4977.800887964513, 5361.544268737201, 5411.188321751769, 5419.132938729785, 5520.254765606672, 5525.419830917776, 5897.144913987448, 6053.303870054912, 6208.4722798561725, 6225.595336303126, 6252.718627661766, 1634.5913521619595, 1690.2478397113412, 1693.7628731977534, 1699.4476795041414, 1700.3878603702658, 1172.0447835014606, 1190.3512585640847, 1225.6200577820905, 1227.7728620205112, 1230.3233221728015, 5851.180834683148, 5893.0, 5906.940930590725, 5914.172015917775, 5925.933332000282, 6322.178116844781, 6326.588771729823, 6330.715117819709, 6334.683265836689, 6350.936490635035, 5732.684702341411, 5839.751695597226, 5845.766169353178, 5905.1142328650185, 5916.5858389913155, 3324.9002529923655, 3340.916477088044, 3345.9718954346354, 3346.481295399217, 3367.225538126245, 6319.6208227506495, 6537.34626537352, 6603.075569420285, 6604.207495100619, 6655.43428808547, 6517.902231466532, 6543.705533251015, 6556.114924485133, 6568.671566421755, 6584.604923704593, 6438.7102580384, 6569.540132190701, 6645.933540664531, 6647.567908085959, 6679.332066793282, 6357.650329231937, 6408.455281301141, 6413.422791948747, 6431.0, 6440.355964403522, 6155.169052178077, 6343.0, 6348.079528468438, 6352.714127864165, 6359.109724638462, 6654.966725166343, 6666.300038496082, 6671.132752742113, 6684.331566843277, 6692.765753198614, 4557.612602928655, 4567.451905771329, 4573.3368661544555, 4577.473590537131, 4582.633389328779, 6359.4640351184, 6400.263969643551, 6442.2591401989375, 6455.61266324022, 6467.741290348334, 6211.503079753699, 6219.595726277777, 6232.580442144598, 6269.96865015672, 6271.843203919939, 6044.184035155374, 6255.249370075438, 6271.749130034747, 6277.771391156074, 6277.905831412441, 6197.81406557804, 6221.5333849960525, 6222.284437289589, 6227.0, 6245.968770156302, 6227.00735764551, 6230.127782110418, 6269.62382257066, 6279.874402512016, 6287.497000239879, 5873.706314684163, 6045.909311360245, 6048.818535443944, 6053.182820319201, 6105.3894610540365], "value": 4828.437405278117, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.8767840271149, 653.9901901471476, 677.6002158726351, 681.4105798484312, 682.9282925292844, 14059.086159399632, 14684.11895286281, 14732.70534589309, 14748.852511474883, 14767.113973161604, 14711.852881471183, 14836.184009879453, 14851.480198193065, 14878.628034299121, 15562.299696513655, 784.1413652050995, 799.808046068945, 809.1463506001166, 836.807534267119, 837.6272558711375, 838.6687258532876, 840.4033136473112, 840.9495430274203, 841.0412130171609, 841.6591280531395, 884.8938127424718, 885.2298500304744, 885.5882014863088, 888.2760550151638, 888.3293113699148, 868.3660927522913, 869.3566760597175, 877.7191298784392, 878.565110270417, 881.5900606218107, 635.5455849067938, 635.7520566978882, 641.983950401239, 642.0369445831253, 644.3588629313816, 15323.616909577238, 15667.686646267011, 15688.137152456611, 15751.133687647174, 15804.446844360507, 14716.264186790617, 15086.924565377212, 15108.320125594375, 15259.923700381538, 15540.533783986499, 12218.083643726755, 12218.144729868913, 12342.938285308606, 12395.504179832797, 12623.558175463906, 3048.5427185922067, 3066.796319844407, 3067.6472205696186, 3087.3979573983083, 3190.9042728718177, 80533.59733201355, 81107.5944620279, 81193.59403202946, 82172.17827821705, 83093.0, 10994.395308258123, 11029.44852757359, 11110.722231944104, 11130.610428634958, 11147.609833655782, 24415.267541973768, 24701.876490617433, 24753.25740227796, 24884.0, 24902.626460602743, 7663.0, 7700.191479894643, 7765.107012693499, 7936.56348900816, 8066.072401673765, 3703.3148867459604, 3740.6446387593137, 3744.475773391727, 3796.962030379661, 3817.217470418613, 4180.707350485498, 4250.978745106255, 4269.423627810268, 4273.401723758676, 4519.231730605805, 841.7222316635496, 847.9152084791594, 848.6096395658067, 854.769212312672, 864.0711235421946, 612.6018088242589, 630.2688880898136, 630.621627023782, 632.2034236861496, 643.9452646525004, 10124.240681948882, 10656.467176641137, 10763.408012559228, 10975.50610222542, 11000.504977276041, 614.6619359352338, 640.961542307463, 643.2731013954295, 646.7606985415332, 653.7189008726199, 4714.952850471452, 5021.548060674557, 5088.185890257615, 5095.261187127846, 5098.745062746848, 74883.25116748872, 75024.62487687636, 75983.0, 78382.0, 78532.21467785363, 48845.55211208683, 54482.18276725852, 60116.0, 66484.66757666277, 69790.953135703, 53462.19806702903, 54291.72854135704, 54376.0, 54497.72751136219, 54765.0, 10802.945985270024, 11183.888161118284, 11272.887271127513, 11421.31472111676, 11541.942290288494, 30821.537676934415, 31027.534586981667, 31112.57761646172, 31393.372132557677, 31975.360492790478, 37920.241595168496, 37993.62006379901, 38788.0, 39449.0, 39711.40432893451, 13863.37614807316, 13992.790108148589, 14185.432582696576, 14204.644883877985, 14327.35526901271, 15040.699186016438, 15125.319360629019, 15140.545783626509, 15174.772378414553, 15735.842641573438, 13016.674583135118, 13295.800562991757, 13412.865871341162, 13502.324883755577, 13897.374618142007, 14199.503017394341, 14240.05114272022, 14712.632184195052, 14746.557603271918, 15250.932434729906, 13413.0, 13820.516281930084, 14085.295735212994, 14335.426582936565, 14654.413823446943, 10782.299150555189, 10904.78190436171, 11457.541698331974, 11475.598354057902, 11788.410579470821, 12025.338606376734, 12292.815607765711, 12316.384180791103, 12443.813342800046, 12473.0], "value": 7178.949031462414, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1494.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 108.0, 108.0, 107.0, 109.0, 108.0, 108.0, 108.0, 108.0, 108.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 122.0, 117.0, 165.0, 119.0, 118.0, 119.0, 116.0, 142.0, 117.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 161.0, 162.0, 161.0, 162.0, 161.0, 162.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 115.0, 78.0, 75.0, 76.0, 74.0, 91.0, 74.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 109.0, 83.0, 84.0, 82.0, 102.0, 83.0, 83.0, 100.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 112.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 148.0, 147.0, 147.0, 148.0, 147.0, 147.0, 148.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 93.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 58.0, 60.0, 58.0, 58.0, 58.0, 58.0, 58.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 56.0, 56.0, 55.0, 54.0, 56.0, 55.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 87.0, 80.0, 80.0, 81.0, 80.0, 80.0, 81.0, 80.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 135.0, 125.0, 129.0, 132.0, 124.0, 126.0, 132.0, 168.0, 128.0], "value": 132.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 180.0, 183.0, 182.0, 180.0, 183.0, 173.0, 191.0, 184.0, 182.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 65.0, 65.0, 65.0, 64.0, 62.0, 65.0, 63.0, 63.0], "value": 64.0, "unit": "score"}]}]} 12:54:59 INFO - Return code: 0 12:54:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:54:59 ERROR - # TBPL SUCCESS # 12:54:59 INFO - Running post-action listener: _resource_record_post_action 12:54:59 INFO - [mozharness: 2017-03-26 19:54:59.721013Z] Finished run-tests step (success) 12:54:59 INFO - Running post-run listener: _resource_record_post_run 12:54:59 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 8192; Write bytes: 272748544; Read time: 176; Write time: 261620 12:54:59 INFO - TinderboxPrint: CPU usage
12.9% 12:54:59 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 176 12:54:59 INFO - TinderboxPrint: I/O write bytes / time
272,748,544 / 261,620 12:54:59 INFO - TinderboxPrint: CPU idle
8,310.1 (87.0%) 12:54:59 INFO - TinderboxPrint: CPU user
1,190.4 (12.5%) 12:54:59 INFO - TinderboxPrint: Swap in / out
0 / 0 12:54:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13848576; Read time: 0; Write time: 3660 12:54:59 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 258895872; Read time: 168; Write time: 257852 12:55:00 INFO - Running post-run listener: _upload_blobber_files 12:55:00 INFO - Blob upload gear active. 12:55:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:55:00 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 12:55:00 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:55:00 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:55:00 INFO - (blobuploader) - INFO - Open directory for files ... 12:55:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:55:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:55:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:01 INFO - (blobuploader) - INFO - Done attempting. 12:55:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:55:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:55:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:06 INFO - (blobuploader) - INFO - Done attempting. 12:55:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:55:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:55:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:07 INFO - (blobuploader) - INFO - Done attempting. 12:55:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:55:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:55:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:55:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:55:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:55:08 INFO - (blobuploader) - INFO - Done attempting. 12:55:08 INFO - (blobuploader) - INFO - Iteration through files over. 12:55:08 INFO - Return code: 0 12:55:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:55:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:55:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c7bd06180114f4d42e9c879dd2a9276fdc5b4e1b160e6a5bec5475c4654a6819de8de1162a49fe2d73bca221e937a3c38f7e199e78ec882c292d03e4570dc7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db5428ddbebb590c1b80b8908818810f9fbc404198d37876893ca68e1457c7c0a255df9e56bd7d6fa189d84655ecf8be192dc6afc0185fe417e7fb088d2ca3ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acaf0c86e40bcab4a1d7d706c8659f3df2310a3d0d699eab2954b27ed5f6196d1aae6b4bd274eac652c938cf0609e4c5e0e40b41ff60c7d7146405464d9b79b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a710fad18f4a2144004ed906c32a6c30d229683f0b655e74e79b567a1f1dd63b5e0060a5bc5f7a1382d2b2f844f9f55665353d12bbd9851dfdeb3b68a4b5bdc"} 12:55:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:55:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:55:08 INFO - Contents: 12:55:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c7bd06180114f4d42e9c879dd2a9276fdc5b4e1b160e6a5bec5475c4654a6819de8de1162a49fe2d73bca221e937a3c38f7e199e78ec882c292d03e4570dc7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db5428ddbebb590c1b80b8908818810f9fbc404198d37876893ca68e1457c7c0a255df9e56bd7d6fa189d84655ecf8be192dc6afc0185fe417e7fb088d2ca3ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acaf0c86e40bcab4a1d7d706c8659f3df2310a3d0d699eab2954b27ed5f6196d1aae6b4bd274eac652c938cf0609e4c5e0e40b41ff60c7d7146405464d9b79b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a710fad18f4a2144004ed906c32a6c30d229683f0b655e74e79b567a1f1dd63b5e0060a5bc5f7a1382d2b2f844f9f55665353d12bbd9851dfdeb3b68a4b5bdc"} 12:55:08 INFO - Running post-run listener: copy_logs_to_upload_dir 12:55:08 INFO - Copying logs to upload dir... 12:55:08 INFO - mkdir: /builds/slave/test/build/upload/logs 12:55:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.856908 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-03-26 12:55:06.737582) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 12:55:06.740582) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c7bd06180114f4d42e9c879dd2a9276fdc5b4e1b160e6a5bec5475c4654a6819de8de1162a49fe2d73bca221e937a3c38f7e199e78ec882c292d03e4570dc7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db5428ddbebb590c1b80b8908818810f9fbc404198d37876893ca68e1457c7c0a255df9e56bd7d6fa189d84655ecf8be192dc6afc0185fe417e7fb088d2ca3ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acaf0c86e40bcab4a1d7d706c8659f3df2310a3d0d699eab2954b27ed5f6196d1aae6b4bd274eac652c938cf0609e4c5e0e40b41ff60c7d7146405464d9b79b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a710fad18f4a2144004ed906c32a6c30d229683f0b655e74e79b567a1f1dd63b5e0060a5bc5f7a1382d2b2f844f9f55665353d12bbd9851dfdeb3b68a4b5bdc"} build_url:https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/FjR3FoP1RZ2AVDaNiiutVQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c7bd06180114f4d42e9c879dd2a9276fdc5b4e1b160e6a5bec5475c4654a6819de8de1162a49fe2d73bca221e937a3c38f7e199e78ec882c292d03e4570dc7d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db5428ddbebb590c1b80b8908818810f9fbc404198d37876893ca68e1457c7c0a255df9e56bd7d6fa189d84655ecf8be192dc6afc0185fe417e7fb088d2ca3ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acaf0c86e40bcab4a1d7d706c8659f3df2310a3d0d699eab2954b27ed5f6196d1aae6b4bd274eac652c938cf0609e4c5e0e40b41ff60c7d7146405464d9b79b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6a710fad18f4a2144004ed906c32a6c30d229683f0b655e74e79b567a1f1dd63b5e0060a5bc5f7a1382d2b2f844f9f55665353d12bbd9851dfdeb3b68a4b5bdc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-26 12:55:06.765237) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:55:06.765523) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1490553317.971543-294859693 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004392 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-26 12:55:06.847718) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 12:55:06.848010) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-26 12:55:06.848305) ========= ========= Total master_lag: 0.14 =========