builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1494552201.28 results: success (0) revision: 2a8e0c4be57d244bf59543750d047b2ab7c8bbdc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:21.277503) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:21.277893) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:21.278159) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:21.314004) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:21.314273) ========= 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=17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-11 18:23:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.8M=0s 2017-05-11 18:23:21 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.194768 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:21.523142) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:21.523458) ========= 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=17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016386 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:21.571932) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 18:23:21.572242) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2a8e0c4be57d244bf59543750d047b2ab7c8bbdc --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 2a8e0c4be57d244bf59543750d047b2ab7c8bbdc --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=17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-11 18:23:21,746 truncating revision to first 12 chars 2017-05-11 18:23:21,746 Setting DEBUG logging. 2017-05-11 18:23:21,746 attempt 1/10 2017-05-11 18:23:21,746 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2a8e0c4be57d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 18:23:22,978 unpacking tar archive at: mozilla-central-2a8e0c4be57d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.561908 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 18:23:23.148035) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:23.151360) ========= script_repo_revision: 2a8e0c4be57d244bf59543750d047b2ab7c8bbdc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:23.151766) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:23.152024) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 18:23:23.161342) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-05-11 18:23:23.161631) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:23:23 INFO - MultiFileLogger online at 20170511 18:23:23 in /builds/slave/test 18:23:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:23:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:23 INFO - {'append_to_log': False, 18:23:23 INFO - 'base_work_dir': '/builds/slave/test', 18:23:23 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:23:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:23 INFO - 'branch': 'Firefox-Non-PGO', 18:23:23 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:23 INFO - 'config_files': ('talos/linux_config.py',), 18:23:23 INFO - 'default_actions': ('clobber', 18:23:23 INFO - 'read-buildbot-config', 18:23:23 INFO - 'download-and-extract', 18:23:23 INFO - 'populate-webroot', 18:23:23 INFO - 'create-virtualenv', 18:23:23 INFO - 'install', 18:23:23 INFO - 'run-tests'), 18:23:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:23 INFO - 'download_minidump_stackwalk': True, 18:23:23 INFO - 'download_symbols': 'ondemand', 18:23:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:23:23 INFO - 'tooltool.py': '/tools/tooltool.py', 18:23:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:23:23 INFO - '/tools/misc-python/virtualenv.py')}, 18:23:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:23 INFO - 'gecko_profile': False, 18:23:23 INFO - 'gecko_profile_interval': 0, 18:23:23 INFO - 'installer_path': 'installer.exe', 18:23:23 INFO - 'log_level': 'info', 18:23:23 INFO - 'log_name': 'talos', 18:23:23 INFO - 'log_to_console': True, 18:23:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:23:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:23:23 INFO - 'opt_config_files': (), 18:23:23 INFO - 'pip_index': False, 18:23:23 INFO - 'suite': 'dromaeojs', 18:23:23 INFO - 'system_bits': '32', 18:23:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:23 INFO - 'title': 'talos-linux64-ix-009', 18:23:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:23 INFO - 'use_talos_json': True, 18:23:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:23 INFO - 'work_dir': 'build'} 18:23:23 INFO - [mozharness: 2017-05-12 01:23:23.510735Z] Running clobber step. 18:23:23 INFO - Running pre-action listener: _resource_record_pre_action 18:23:23 INFO - Running main action method: clobber 18:23:23 INFO - rmtree: /builds/slave/test/build 18:23:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:24 INFO - Running post-action listener: _resource_record_post_action 18:23:24 INFO - [mozharness: 2017-05-12 01:23:24.398539Z] Finished clobber step (success) 18:23:24 INFO - [mozharness: 2017-05-12 01:23:24.398639Z] Running read-buildbot-config step. 18:23:24 INFO - Running pre-action listener: _resource_record_pre_action 18:23:24 INFO - Running main action method: read_buildbot_config 18:23:24 INFO - Using buildbot properties: 18:23:24 INFO - { 18:23:24 INFO - "project": "", 18:23:24 INFO - "product": "firefox", 18:23:24 INFO - "who": "kwierso@gmail.com", 18:23:24 INFO - "installer_path": "public/build/target.tar.bz2", 18:23:24 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:23:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:23:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:23:24 INFO - "stage_platform": "linux64", 18:23:24 INFO - "basedir": "/builds/slave/test", 18:23:24 INFO - "buildnumber": 989, 18:23:24 INFO - "platform": "ubuntu64_hw", 18:23:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:23:24 INFO - "slavebuilddir": "test", 18:23:24 INFO - "taskId": "UATJ4YZnQDucCMSyC1nvkA", 18:23:24 INFO - "branch": "mozilla-central", 18:23:24 INFO - "script_repo_revision": "production", 18:23:24 INFO - "revision": "2a8e0c4be57d244bf59543750d047b2ab7c8bbdc", 18:23:24 INFO - "slavename": "talos-linux64-ix-009", 18:23:24 INFO - "repo_path": "mozilla-central" 18:23:24 INFO - } 18:23:24 INFO - Finding installer, test and symbols from parent task. 18:23:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UATJ4YZnQDucCMSyC1nvkA'}, attempt #1 18:23:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UATJ4YZnQDucCMSyC1nvkA'}, attempt #1 18:23:25 INFO - Task dependencies: dGUIyOthRJK_MESGw0MYbQ 18:23:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ'}, attempt #1 18:23:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.tar.bz2 18:23:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/test_packages.json 18:23:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.crashreporter-symbols.zip 18:23:25 INFO - Running post-action listener: _resource_record_post_action 18:23:25 INFO - [mozharness: 2017-05-12 01:23:25.756544Z] Finished read-buildbot-config step (success) 18:23:25 INFO - [mozharness: 2017-05-12 01:23:25.756760Z] Running download-and-extract step. 18:23:25 INFO - Running pre-action listener: _resource_record_pre_action 18:23:25 INFO - Running main action method: download_and_extract 18:23:25 INFO - mkdir: /builds/slave/test/build/tests 18:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:23:25 INFO - trying https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.test_packages.json 18:23:25 INFO - Downloading https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:23:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:23:26 INFO - Downloaded 986 bytes. 18:23:26 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:23:26 INFO - Using the following test package requirements: 18:23:26 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:23:26 INFO - u'common': [u'target.common.tests.zip'], 18:23:26 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:23:26 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:23:26 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:23:26 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:23:26 INFO - u'mozbase': [u'target.common.tests.zip'], 18:23:26 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:23:26 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:23:26 INFO - u'web-platform': [u'target.common.tests.zip', 18:23:26 INFO - u'target.web-platform.tests.zip'], 18:23:26 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:23:26 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:23:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.common.tests.zip 18:23:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:23:26 INFO - Fetch https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.common.tests.zip into memory 18:23:27 INFO - Content-Length response header: 58591020 18:23:27 INFO - Bytes received: 58591020 18:23:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:23:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.common.tests.zip 18:23:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:23:37 INFO - Fetch https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.common.tests.zip into memory 18:23:38 INFO - Content-Length response header: 58591020 18:23:38 INFO - Bytes received: 58591020 18:23:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.talos.tests.zip 18:23:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:23:50 INFO - Fetch https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.talos.tests.zip into memory 18:23:51 INFO - Content-Length response header: 14409411 18:23:51 INFO - Bytes received: 14409411 18:23:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:23:51 INFO - trying https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.tar.bz2 18:23:51 INFO - Downloading https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:23:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:23:52 INFO - Downloaded 63323464 bytes. 18:23:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.tar.bz2 18:23:52 INFO - mkdir: /builds/slave/test/properties 18:23:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:23:52 INFO - Writing to file /builds/slave/test/properties/build_url 18:23:52 INFO - Contents: 18:23:52 INFO - build_url:https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.tar.bz2 18:23:52 INFO - Running post-action listener: _resource_record_post_action 18:23:52 INFO - Running post-action listener: set_extra_try_arguments 18:23:52 INFO - [mozharness: 2017-05-12 01:23:52.488678Z] Finished download-and-extract step (success) 18:23:52 INFO - [mozharness: 2017-05-12 01:23:52.488774Z] Running populate-webroot step. 18:23:52 INFO - Running pre-action listener: _resource_record_pre_action 18:23:52 INFO - Running main action method: populate_webroot 18:23:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:23:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:23:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:23:52 INFO - '--tppagecycles', 18:23:52 INFO - '7'], 18:23:52 INFO - 'tests': ['tsvgm']}}, 18:23:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:23:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:23:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:23:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:23:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:23:52 INFO - 'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:23:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:23:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:23:52 INFO - 'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['damp', 'tps']}, 18:23:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:23:52 INFO - 'tests': ['damp', 'tps']}, 18:23:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['dromaeo_dom']}, 18:23:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:23:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:23:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:23:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['a11yr', 18:23:52 INFO - 'ts_paint', 18:23:52 INFO - 'tpaint', 18:23:52 INFO - 'sessionrestore', 18:23:52 INFO - 'sessionrestore_no_auto_restore', 18:23:52 INFO - 'tabpaint']}, 18:23:52 INFO - 'other-e10s': {'tests': ['a11yr', 18:23:52 INFO - 'ts_paint', 18:23:52 INFO - 'tpaint', 18:23:52 INFO - 'sessionrestore', 18:23:52 INFO - 'sessionrestore_no_auto_restore', 18:23:52 INFO - 'tabpaint']}, 18:23:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:23:52 INFO - 'ts_paint', 18:23:52 INFO - 'tpaint', 18:23:52 INFO - 'sessionrestore', 18:23:52 INFO - 'sessionrestore_no_auto_restore', 18:23:52 INFO - 'tabpaint']}, 18:23:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:23:52 INFO - 'ts_paint', 18:23:52 INFO - 'tpaint', 18:23:52 INFO - 'sessionrestore', 18:23:52 INFO - 'sessionrestore_no_auto_restore', 18:23:52 INFO - 'tabpaint']}, 18:23:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['a11yr', 18:23:52 INFO - 'ts_paint', 18:23:52 INFO - 'tpaint', 18:23:52 INFO - 'sessionrestore', 18:23:52 INFO - 'sessionrestore_no_auto_restore', 18:23:52 INFO - 'tabpaint']}, 18:23:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['a11yr', 18:23:52 INFO - 'ts_paint', 18:23:52 INFO - 'tpaint', 18:23:52 INFO - 'sessionrestore', 18:23:52 INFO - 'sessionrestore_no_auto_restore', 18:23:52 INFO - 'tabpaint']}, 18:23:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:23:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:23:52 INFO - 'bloom_basic_ref']}, 18:23:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['tsvgx', 18:23:52 INFO - 'tsvgr_opacity', 18:23:52 INFO - 'tart', 18:23:52 INFO - 'tscrollx', 18:23:52 INFO - 'cart', 18:23:52 INFO - 'tsvg_static']}, 18:23:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:23:52 INFO - 'tsvgr_opacity', 18:23:52 INFO - 'tart', 18:23:52 INFO - 'tscrollx', 18:23:52 INFO - 'cart', 18:23:52 INFO - 'tsvg_static']}, 18:23:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:23:52 INFO - 'talos_options': ['--disable-e10s'], 18:23:52 INFO - 'tests': ['tp5o']}, 18:23:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:23:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:23:52 INFO - 'talos_options': ['--disable-e10s', 18:23:52 INFO - '--xperf_path', 18:23:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:52 INFO - 'tests': ['tp5n']}, 18:23:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:23:52 INFO - 'talos_options': ['--xperf_path', 18:23:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:23:52 INFO - 'tests': ['tp5n']}}, 18:23:52 INFO - 'talos.zip': {'path': '', 18:23:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:23:52 INFO - Running post-action listener: _resource_record_post_action 18:23:52 INFO - [mozharness: 2017-05-12 01:23:52.502539Z] Finished populate-webroot step (success) 18:23:52 INFO - [mozharness: 2017-05-12 01:23:52.502627Z] Running create-virtualenv step. 18:23:52 INFO - Running pre-action listener: _resource_record_pre_action 18:23:52 INFO - Running main action method: create_virtualenv 18:23:52 INFO - Creating virtualenv /builds/slave/test/build/venv 18:23:52 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 18:23:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:23:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:23:52 INFO - Using real prefix '/usr' 18:23:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:23:53 INFO - Installing distribute.............................................................................................................................................................................................done. 18:23:56 INFO - Installing pip.................done. 18:23:56 INFO - Return code: 0 18:23:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:23:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:23:57 INFO - Reading from file tmpfile_stdout 18:23:57 INFO - Output received: 18:23:57 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) 18:23:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:57 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:23:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:23:57 INFO - Using env: {'DISPLAY': ':0', 18:23:57 INFO - 'HOME': '/home/cltbld', 18:23:57 INFO - 'LANG': 'en_US.UTF-8', 18:23:57 INFO - 'LANGUAGE': 'en_US:en', 18:23:57 INFO - 'LOGNAME': 'cltbld', 18:23:57 INFO - 'MAIL': '/var/mail/cltbld', 18:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:57 INFO - 'MOZ_NO_REMOTE': '1', 18:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:57 INFO - 'NO_EM_RESTART': '1', 18:23:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:57 INFO - 'PWD': '/builds/slave/test', 18:23:57 INFO - 'SHELL': '/bin/bash', 18:23:57 INFO - 'SHLVL': '1', 18:23:57 INFO - 'TERM': 'linux', 18:23:57 INFO - 'TMOUT': '86400', 18:23:57 INFO - 'USER': 'cltbld', 18:23:57 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:57 INFO - '_': '/tools/buildbot/bin/python'} 18:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:58 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 18:23:58 INFO - Cleaning up... 18:23:58 INFO - Return code: 0 18:23:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:23:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:23:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:23:58 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:23:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:23:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:23:58 INFO - Using env: {'DISPLAY': ':0', 18:23:58 INFO - 'HOME': '/home/cltbld', 18:23:58 INFO - 'LANG': 'en_US.UTF-8', 18:23:58 INFO - 'LANGUAGE': 'en_US:en', 18:23:58 INFO - 'LOGNAME': 'cltbld', 18:23:58 INFO - 'MAIL': '/var/mail/cltbld', 18:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:58 INFO - 'MOZ_NO_REMOTE': '1', 18:23:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:58 INFO - 'NO_EM_RESTART': '1', 18:23:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:58 INFO - 'PWD': '/builds/slave/test', 18:23:58 INFO - 'SHELL': '/bin/bash', 18:23:58 INFO - 'SHLVL': '1', 18:23:58 INFO - 'TERM': 'linux', 18:23:58 INFO - 'TMOUT': '86400', 18:23:58 INFO - 'USER': 'cltbld', 18:23:58 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:58 INFO - '_': '/tools/buildbot/bin/python'} 18:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:23:58 INFO - Downloading/unpacking psutil>=3.1.1 18:23:58 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 18:23:58 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 18:23:58 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 18:23:58 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 18:23:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:23:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:23:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:23:59 INFO - Installing collected packages: psutil 18:23:59 INFO - Running setup.py install for psutil 18:23:59 INFO - building 'psutil._psutil_linux' extension 18:23:59 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 18:24:00 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 18:24:00 INFO - building 'psutil._psutil_posix' extension 18:24:00 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 18:24:00 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 18:24:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:01 INFO - Successfully installed psutil 18:24:01 INFO - Cleaning up... 18:24:01 INFO - Return code: 0 18:24:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:01 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:24:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:24:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:24:01 INFO - Using env: {'DISPLAY': ':0', 18:24:01 INFO - 'HOME': '/home/cltbld', 18:24:01 INFO - 'LANG': 'en_US.UTF-8', 18:24:01 INFO - 'LANGUAGE': 'en_US:en', 18:24:01 INFO - 'LOGNAME': 'cltbld', 18:24:01 INFO - 'MAIL': '/var/mail/cltbld', 18:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:01 INFO - 'MOZ_NO_REMOTE': '1', 18:24:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:01 INFO - 'NO_EM_RESTART': '1', 18:24:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:01 INFO - 'PWD': '/builds/slave/test', 18:24:01 INFO - 'SHELL': '/bin/bash', 18:24:01 INFO - 'SHLVL': '1', 18:24:01 INFO - 'TERM': 'linux', 18:24:01 INFO - 'TMOUT': '86400', 18:24:01 INFO - 'USER': 'cltbld', 18:24:01 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:01 INFO - '_': '/tools/buildbot/bin/python'} 18:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:24:01 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 18:24:01 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 18:24:01 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 18:24:01 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 18:24:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:24:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:24:02 INFO - Installing collected packages: mozsystemmonitor 18:24:02 INFO - Running setup.py install for mozsystemmonitor 18:24:02 INFO - Successfully installed mozsystemmonitor 18:24:02 INFO - Cleaning up... 18:24:02 INFO - Return code: 0 18:24:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24: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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:24:02 INFO - Using env: {'DISPLAY': ':0', 18:24:02 INFO - 'HOME': '/home/cltbld', 18:24:02 INFO - 'LANG': 'en_US.UTF-8', 18:24:02 INFO - 'LANGUAGE': 'en_US:en', 18:24:02 INFO - 'LOGNAME': 'cltbld', 18:24:02 INFO - 'MAIL': '/var/mail/cltbld', 18:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:02 INFO - 'MOZ_NO_REMOTE': '1', 18:24:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:02 INFO - 'NO_EM_RESTART': '1', 18:24:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:02 INFO - 'PWD': '/builds/slave/test', 18:24:02 INFO - 'SHELL': '/bin/bash', 18:24:02 INFO - 'SHLVL': '1', 18:24:02 INFO - 'TERM': 'linux', 18:24:02 INFO - 'TMOUT': '86400', 18:24:02 INFO - 'USER': 'cltbld', 18:24:02 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:02 INFO - '_': '/tools/buildbot/bin/python'} 18:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:02 INFO - Downloading/unpacking jsonschema==2.5.1 18:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:24:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:24:03 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 18:24:03 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 18:24:03 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 18:24:03 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 18:24:03 INFO - Downloading functools32-3.2.3-2.tar.gz 18:24:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:24:04 INFO - warning: no files found matching '*.txt' 18:24:04 INFO - no previously-included directories found matching 'build' 18:24:04 INFO - no previously-included directories found matching 'dist' 18:24:04 INFO - no previously-included directories found matching '.git*' 18:24:04 INFO - Installing collected packages: jsonschema, functools32 18:24:04 INFO - Running setup.py install for functools32 18:24:04 INFO - warning: no files found matching '*.txt' 18:24:04 INFO - no previously-included directories found matching 'build' 18:24:04 INFO - no previously-included directories found matching 'dist' 18:24:04 INFO - no previously-included directories found matching '.git*' 18:24:04 INFO - Successfully installed jsonschema functools32 18:24:04 INFO - Cleaning up... 18:24:04 INFO - Return code: 0 18:24:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:04 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:24:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:24:04 INFO - Using env: {'DISPLAY': ':0', 18:24:04 INFO - 'HOME': '/home/cltbld', 18:24:04 INFO - 'LANG': 'en_US.UTF-8', 18:24:04 INFO - 'LANGUAGE': 'en_US:en', 18:24:04 INFO - 'LOGNAME': 'cltbld', 18:24:04 INFO - 'MAIL': '/var/mail/cltbld', 18:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:04 INFO - 'MOZ_NO_REMOTE': '1', 18:24:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:04 INFO - 'NO_EM_RESTART': '1', 18:24:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:04 INFO - 'PWD': '/builds/slave/test', 18:24:04 INFO - 'SHELL': '/bin/bash', 18:24:04 INFO - 'SHLVL': '1', 18:24:04 INFO - 'TERM': 'linux', 18:24:04 INFO - 'TMOUT': '86400', 18:24:04 INFO - 'USER': 'cltbld', 18:24:04 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:04 INFO - '_': '/tools/buildbot/bin/python'} 18:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:24:04 INFO - Cleaning up... 18:24:04 INFO - Return code: 0 18:24:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:04 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:24:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:24:04 INFO - Using env: {'DISPLAY': ':0', 18:24:04 INFO - 'HOME': '/home/cltbld', 18:24:04 INFO - 'LANG': 'en_US.UTF-8', 18:24:04 INFO - 'LANGUAGE': 'en_US:en', 18:24:04 INFO - 'LOGNAME': 'cltbld', 18:24:04 INFO - 'MAIL': '/var/mail/cltbld', 18:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:04 INFO - 'MOZ_NO_REMOTE': '1', 18:24:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:04 INFO - 'NO_EM_RESTART': '1', 18:24:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:04 INFO - 'PWD': '/builds/slave/test', 18:24:04 INFO - 'SHELL': '/bin/bash', 18:24:04 INFO - 'SHLVL': '1', 18:24:04 INFO - 'TERM': 'linux', 18:24:04 INFO - 'TMOUT': '86400', 18:24:04 INFO - 'USER': 'cltbld', 18:24:04 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:04 INFO - '_': '/tools/buildbot/bin/python'} 18:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:04 INFO - Downloading/unpacking blobuploader==1.2.4 18:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:24:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:24:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:24:05 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 18:24:05 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 18:24:05 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 18:24:05 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 18:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:24:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:06 INFO - Downloading docopt-0.6.1.tar.gz 18:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:24:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:24:06 INFO - Running setup.py install for blobuploader 18:24:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:24:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:24:06 INFO - Running setup.py install for requests 18:24:06 INFO - Running setup.py install for docopt 18:24:07 INFO - Successfully installed blobuploader requests docopt 18:24:07 INFO - Cleaning up... 18:24:07 INFO - Return code: 0 18:24:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:07 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:24:07 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:24:07 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:07 INFO - Using env: {'DISPLAY': ':0', 18:24:07 INFO - 'HOME': '/home/cltbld', 18:24:07 INFO - 'LANG': 'en_US.UTF-8', 18:24:07 INFO - 'LANGUAGE': 'en_US:en', 18:24:07 INFO - 'LOGNAME': 'cltbld', 18:24:07 INFO - 'MAIL': '/var/mail/cltbld', 18:24:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:07 INFO - 'MOZ_NO_REMOTE': '1', 18:24:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:07 INFO - 'NO_EM_RESTART': '1', 18:24:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:07 INFO - 'PWD': '/builds/slave/test', 18:24:07 INFO - 'SHELL': '/bin/bash', 18:24:07 INFO - 'SHLVL': '1', 18:24:07 INFO - 'TERM': 'linux', 18:24:07 INFO - 'TMOUT': '86400', 18:24:07 INFO - 'USER': 'cltbld', 18:24:07 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:07 INFO - '_': '/tools/buildbot/bin/python'} 18:24:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:07 INFO - Running setup.py (path:/tmp/pip-iN31Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:07 INFO - Running setup.py (path:/tmp/pip-MKuE6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:07 INFO - Running setup.py (path:/tmp/pip-A6jFTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:07 INFO - Running setup.py (path:/tmp/pip-aOknbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:07 INFO - Running setup.py (path:/tmp/pip-pFdq7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:07 INFO - Running setup.py (path:/tmp/pip-vPmZcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:07 INFO - Running setup.py (path:/tmp/pip-fjOdmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:07 INFO - Running setup.py (path:/tmp/pip-Q_ZEvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:07 INFO - Running setup.py (path:/tmp/pip-Kjuq7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:07 INFO - Running setup.py (path:/tmp/pip-IQsK_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:08 INFO - Running setup.py (path:/tmp/pip-3yeqMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:08 INFO - Running setup.py (path:/tmp/pip-Cs1Lzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:08 INFO - Running setup.py (path:/tmp/pip-CwDGpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:08 INFO - Running setup.py (path:/tmp/pip-pm3Juv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:08 INFO - Running setup.py (path:/tmp/pip-wF5ZAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:08 INFO - Running setup.py (path:/tmp/pip-Pj0BkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:08 INFO - Running setup.py (path:/tmp/pip-tu_08t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:24:08 INFO - Running setup.py install for manifestparser 18:24:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:24:08 INFO - Running setup.py install for mozcrash 18:24:08 INFO - Running setup.py install for mozdebug 18:24:08 INFO - Running setup.py install for mozdevice 18:24:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:24:08 INFO - Running setup.py install for mozfile 18:24:09 INFO - Running setup.py install for mozhttpd 18:24:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Running setup.py install for mozinfo 18:24:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Running setup.py install for mozInstall 18:24:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Running setup.py install for mozleak 18:24:09 INFO - Running setup.py install for mozlog 18:24:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Running setup.py install for moznetwork 18:24:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Running setup.py install for mozprocess 18:24:09 INFO - Running setup.py install for mozprofile 18:24:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Running setup.py install for mozrunner 18:24:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:24:09 INFO - Running setup.py install for mozscreenshot 18:24:10 INFO - Running setup.py install for moztest 18:24:10 INFO - Running setup.py install for mozversion 18:24:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:24:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:24:10 INFO - Cleaning up... 18:24:10 INFO - Return code: 0 18:24:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:10 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:24:10 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:24:10 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:10 INFO - Using env: {'DISPLAY': ':0', 18:24:10 INFO - 'HOME': '/home/cltbld', 18:24:10 INFO - 'LANG': 'en_US.UTF-8', 18:24:10 INFO - 'LANGUAGE': 'en_US:en', 18:24:10 INFO - 'LOGNAME': 'cltbld', 18:24:10 INFO - 'MAIL': '/var/mail/cltbld', 18:24:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:10 INFO - 'MOZ_NO_REMOTE': '1', 18:24:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:10 INFO - 'NO_EM_RESTART': '1', 18:24:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:10 INFO - 'PWD': '/builds/slave/test', 18:24:10 INFO - 'SHELL': '/bin/bash', 18:24:10 INFO - 'SHLVL': '1', 18:24:10 INFO - 'TERM': 'linux', 18:24:10 INFO - 'TMOUT': '86400', 18:24:10 INFO - 'USER': 'cltbld', 18:24:10 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:10 INFO - '_': '/tools/buildbot/bin/python'} 18:24:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:10 INFO - Running setup.py (path:/tmp/pip-ap8H3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:10 INFO - Running setup.py (path:/tmp/pip-wJDxJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:10 INFO - Running setup.py (path:/tmp/pip-mEcoGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:10 INFO - Running setup.py (path:/tmp/pip-aJk4TQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:10 INFO - Running setup.py (path:/tmp/pip-wkGw8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:10 INFO - Running setup.py (path:/tmp/pip-ztJKSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:10 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)) 18:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:10 INFO - Running setup.py (path:/tmp/pip-m2jktV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:10 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:11 INFO - Running setup.py (path:/tmp/pip-ZLCSHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:11 INFO - Running setup.py (path:/tmp/pip-gAfhV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:11 INFO - Running setup.py (path:/tmp/pip-3uyDbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:11 INFO - Running setup.py (path:/tmp/pip-wr95P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:11 INFO - Running setup.py (path:/tmp/pip-gQCIXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:11 INFO - Running setup.py (path:/tmp/pip-khuhhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:11 INFO - Running setup.py (path:/tmp/pip-H_7u8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:11 INFO - Running setup.py (path:/tmp/pip-v_y5OM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:11 INFO - Running setup.py (path:/tmp/pip-BKObZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:11 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)) 18:24:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:11 INFO - Running setup.py (path:/tmp/pip-d9PWdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:11 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)) 18:24:11 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)) 18:24:11 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)) 18:24:11 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)) 18:24:11 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:24:11 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:24:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:11 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 18:24:11 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 18:24:11 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 18:24:11 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 18:24:12 INFO - Downloading blessings-1.6.tar.gz 18:24:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:24:12 INFO - Installing collected packages: blessings 18:24:12 INFO - Running setup.py install for blessings 18:24:13 INFO - Successfully installed blessings 18:24:13 INFO - Cleaning up... 18:24:13 INFO - Return code: 0 18:24:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:24:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:13 INFO - Reading from file tmpfile_stdout 18:24:13 INFO - Current package versions: 18:24:13 INFO - argparse == 1.2.1 18:24:13 INFO - blessings == 1.6 18:24:13 INFO - blobuploader == 1.2.4 18:24:13 INFO - docopt == 0.6.1 18:24:13 INFO - functools32 == 3.2.3-2 18:24:13 INFO - jsonschema == 2.5.1 18:24:13 INFO - manifestparser == 1.1 18:24:13 INFO - mozInstall == 1.12 18:24:13 INFO - mozcrash == 1.0 18:24:13 INFO - mozdebug == 0.1 18:24:13 INFO - mozdevice == 0.50 18:24:13 INFO - mozfile == 1.2 18:24:13 INFO - mozhttpd == 0.7 18:24:13 INFO - mozinfo == 0.9 18:24:13 INFO - mozleak == 0.1 18:24:13 INFO - mozlog == 3.4 18:24:13 INFO - moznetwork == 0.27 18:24:13 INFO - mozprocess == 0.25 18:24:13 INFO - mozprofile == 0.28 18:24:13 INFO - mozrunner == 6.13 18:24:13 INFO - mozscreenshot == 0.1 18:24:13 INFO - mozsystemmonitor == 0.3 18:24:13 INFO - moztest == 0.8 18:24:13 INFO - mozversion == 1.4 18:24:13 INFO - psutil == 3.1.1 18:24:13 INFO - requests == 1.2.3 18:24:13 INFO - wsgiref == 0.1.2 18:24:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:13 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:24:13 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:24:13 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:24:13 INFO - Using env: {'DISPLAY': ':0', 18:24:13 INFO - 'HOME': '/home/cltbld', 18:24:13 INFO - 'LANG': 'en_US.UTF-8', 18:24:13 INFO - 'LANGUAGE': 'en_US:en', 18:24:13 INFO - 'LOGNAME': 'cltbld', 18:24:13 INFO - 'MAIL': '/var/mail/cltbld', 18:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:13 INFO - 'MOZ_NO_REMOTE': '1', 18:24:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:13 INFO - 'NO_EM_RESTART': '1', 18:24:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:13 INFO - 'PWD': '/builds/slave/test', 18:24:13 INFO - 'SHELL': '/bin/bash', 18:24:13 INFO - 'SHLVL': '1', 18:24:13 INFO - 'TERM': 'linux', 18:24:13 INFO - 'TMOUT': '86400', 18:24:13 INFO - 'USER': 'cltbld', 18:24:13 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:13 INFO - '_': '/tools/buildbot/bin/python'} 18:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 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)) 18:24:13 INFO - Cleaning up... 18:24:13 INFO - Return code: 0 18:24:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:13 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/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 0x27bea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2964770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, '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': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', '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 18:24:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:24:13 INFO - Using env: {'DISPLAY': ':0', 18:24:13 INFO - 'HOME': '/home/cltbld', 18:24:13 INFO - 'LANG': 'en_US.UTF-8', 18:24:13 INFO - 'LANGUAGE': 'en_US:en', 18:24:13 INFO - 'LOGNAME': 'cltbld', 18:24:13 INFO - 'MAIL': '/var/mail/cltbld', 18:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:13 INFO - 'MOZ_NO_REMOTE': '1', 18:24:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:13 INFO - 'NO_EM_RESTART': '1', 18:24:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:13 INFO - 'PWD': '/builds/slave/test', 18:24:13 INFO - 'SHELL': '/bin/bash', 18:24:13 INFO - 'SHLVL': '1', 18:24:13 INFO - 'TERM': 'linux', 18:24:13 INFO - 'TMOUT': '86400', 18:24:13 INFO - 'USER': 'cltbld', 18:24:13 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:13 INFO - '_': '/tools/buildbot/bin/python'} 18:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:24:13 INFO - Cleaning up... 18:24:13 INFO - Return code: 0 18:24:13 INFO - Running post-action listener: _resource_record_post_action 18:24:13 INFO - Running post-action listener: _start_resource_monitoring 18:24:13 INFO - Starting resource monitoring. 18:24:13 INFO - [mozharness: 2017-05-12 01:24:13.790056Z] Finished create-virtualenv step (success) 18:24:13 INFO - [mozharness: 2017-05-12 01:24:13.791042Z] Running install step. 18:24:13 INFO - Running pre-action listener: _resource_record_pre_action 18:24:13 INFO - Running main action method: install 18:24:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:13 INFO - Reading from file tmpfile_stdout 18:24:13 INFO - Detecting whether we're running mozinstall >=1.0... 18:24:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:24:14 INFO - Reading from file tmpfile_stdout 18:24:14 INFO - Output received: 18:24:14 INFO - Usage: mozinstall [options] installer 18:24:14 INFO - Options: 18:24:14 INFO - -h, --help show this help message and exit 18:24:14 INFO - -d DEST, --destination=DEST 18:24:14 INFO - Directory to install application into. [default: 18:24:14 INFO - "/builds/slave/test"] 18:24:14 INFO - --app=APP Application being installed. [default: firefox] 18:24:14 INFO - mkdir: /builds/slave/test/build/application 18:24:14 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'] 18:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:24:29 INFO - Reading from file tmpfile_stdout 18:24:29 INFO - Output received: 18:24:29 INFO - /builds/slave/test/build/application/firefox/firefox 18:24:29 INFO - Running post-action listener: _resource_record_post_action 18:24:29 INFO - [mozharness: 2017-05-12 01:24:29.980747Z] Finished install step (success) 18:24:29 INFO - [mozharness: 2017-05-12 01:24:29.980990Z] Running run-tests step. 18:24:29 INFO - Running pre-action listener: _resource_record_pre_action 18:24:29 INFO - Running main action method: run_tests 18:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:24:29 INFO - Python 2.7.3 18:24:29 INFO - Return code: 0 18:24:29 INFO - grabbing minidump binary from tooltool 18:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:24:29 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 0x2961a30>, '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 0x29627f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2962ca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:24:29 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 18:24:29 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 18:24:29 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 18:24:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:24:30 INFO - Return code: 0 18:24:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:24:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:24:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:24:30 INFO - ENV: RUST_BACKTRACE is now 1 18:24:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:24:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:24:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 18:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 18:24:30 INFO - Using env: {'DISPLAY': ':0', 18:24:30 INFO - 'HOME': '/home/cltbld', 18:24:30 INFO - 'LANG': 'en_US.UTF-8', 18:24:30 INFO - 'LANGUAGE': 'en_US:en', 18:24:30 INFO - 'LOGNAME': 'cltbld', 18:24:30 INFO - 'MAIL': '/var/mail/cltbld', 18:24:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:24:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:30 INFO - 'MOZ_NO_REMOTE': '1', 18:24:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:24:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:24:30 INFO - 'NO_EM_RESTART': '1', 18:24:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:30 INFO - 'PWD': '/builds/slave/test', 18:24:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:24:30 INFO - 'RUST_BACKTRACE': '1', 18:24:30 INFO - 'SHELL': '/bin/bash', 18:24:30 INFO - 'SHLVL': '1', 18:24:30 INFO - 'TERM': 'linux', 18:24:30 INFO - 'TMOUT': '86400', 18:24:30 INFO - 'USER': 'cltbld', 18:24:30 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444', 18:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:30 INFO - '_': '/tools/buildbot/bin/python'} 18:24:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 18:24:30 INFO - mozversion application_buildid: 20170512003303 18:24:30 INFO - mozversion application_changeset: 2a8e0c4be57d244bf59543750d047b2ab7c8bbdc 18:24:30 INFO - mozversion application_display_name: Nightly 18:24:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:24:30 INFO - mozversion application_name: Firefox 18:24:30 INFO - mozversion application_remotingname: firefox 18:24:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:24:30 INFO - mozversion application_vendor: Mozilla 18:24:30 INFO - mozversion application_version: 55.0a1 18:24:30 INFO - mozversion platform_buildid: 20170512003303 18:24:30 INFO - mozversion platform_changeset: 2a8e0c4be57d244bf59543750d047b2ab7c8bbdc 18:24:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:24:30 INFO - mozversion platform_version: 55.0a1 18:24:30 INFO - using testdate: 1494552270 18:24:30 INFO - actual date: 1494552270 18:24:30 INFO - starting webserver on 'localhost:46533' 18:24:30 INFO - SUITE-START | Running 2 tests 18:24:30 INFO - TEST-START | dromaeo_css 18:24:30 INFO - operating with platform_type : linux_ 18:24:30 INFO - Initialising browser for dromaeo_css test... 18:24:30 INFO - TEST-INFO | started process 3310 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0dizt2/profile http://localhost:46533/getInfo.html) 18:24:35 INFO - PID 3310 | __metrics Screen width/height:1600/1200 18:24:35 INFO - PID 3310 | colorDepth:24 18:24:35 INFO - PID 3310 | Browser inner width/height: 1024/695 18:24:35 INFO - PID 3310 | __metrics 18:24:36 INFO - PID 3310 | 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" 18:24:36 INFO - PID 3310 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:24:37 INFO - TEST-INFO | 3310: exit 0 18:24:37 INFO - Browser initialized. 18:24:37 INFO - Running cycle 1/1 for dromaeo_css test... 18:24:37 INFO - TEST-INFO | started process 3405 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0dizt2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:49 INFO - PID 3405 | RSS: Main: 148525056 18:24:49 INFO - PID 3405 | 18:27:55 INFO - PID 3405 | Cycle 1(1): loaded http://localhost:46533/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46533/tests/dromaeo/cssquery-ext.html) 18:27:57 INFO - PID 3405 | RSS: Main: 282972160 18:27:57 INFO - PID 3405 | 18:31:00 INFO - PID 3405 | Cycle 1(1): loaded http://localhost:46533/tests/dromaeo/cssquery-ext.html (next: http://localhost:46533/tests/dromaeo/cssquery-jquery.html) 18:31:00 INFO - PID 3405 | RSS: Main: 233566208 18:31:00 INFO - PID 3405 | 18:34:02 INFO - PID 3405 | Cycle 1(1): loaded http://localhost:46533/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46533/tests/dromaeo/cssquery-mootools.html) 18:34:03 INFO - PID 3405 | RSS: Main: 181186560 18:34:03 INFO - PID 3405 | 18:37:05 INFO - PID 3405 | Cycle 1(1): loaded http://localhost:46533/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46533/tests/dromaeo/cssquery-prototype.html) 18:37:05 INFO - PID 3405 | RSS: Main: 216330240 18:37:05 INFO - PID 3405 | 18:40:08 INFO - PID 3405 | Cycle 1(1): loaded http://localhost:46533/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46533/tests/dromaeo/cssquery-yui.html) 18:40:09 INFO - PID 3405 | RSS: Main: 180559872 18:40:09 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | Cycle 1(1): loaded http://localhost:46533/tests/dromaeo/cssquery-yui.html (next: http://localhost:46533/tests/dromaeo/cssquery-dojo.html) 18:43:11 INFO - PID 3405 | RSS: Main: 235524096 18:43:11 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | __start_tp_report 18:43:11 INFO - PID 3405 | _x_x_mozilla_page_load 18:43:11 INFO - PID 3405 | _x_x_mozilla_page_load_details 18:43:11 INFO - PID 3405 | |i|pagename|runs| 18:43:11 INFO - PID 3405 | |0;dojo.html;2685.3152446126237;2711.0511321037625;2743.58846173074;2747.120921305183;2748.065657676391;4859.951400485994;5044.268581055745;5132.538071573557;5163.7418129093585;5204.479552044793;5102.566281866037;5113.821016264432;5155.639105262633;5175.197844334125;5190.688558686472;4232.513260974981;4435.889102772424;4544.295634176708;4608.631309495232;4613.192691279013;4475;4556.567126123013;4576.427946506687;4617.122746678137;4634.606058485033;4530.546945305476;4531.682782205247;4546.954530454719;4556.134334476472;4560.863174104749;4535.931961020588;4537.3647689323525;4542.136993971168;4547.499775024745;4555.74943378114;4500.189965806154;4501.774911254457;4515.864524064284;4517.457905051382;4520.773961301922;5024.748762561857;5082.059818933509;5120.334356533664;5171.974140129275;5181.974090129525;5571.609987300891;5584.246126772874;5585.1063829787445;5585.301837270341;5587.8882422351735;5587.944120558783;5620.072688006474;5633.464820842013;5649.802256921029;5706.971465142689;5049.065922804293;5112.233165025276;5112.258722485219;5116.590672746172;5182.378114626269;5703.058995265735;5728.198052272685;5738.684372359477;5745.482906538432;5799.39106393831;5255.369355677267;5329.333833270841;5330.520253177233;5332.413434522199;5335.759890804846;5406.405110492414;5415.241866138744;5421.83734487966;5433.891322173535;5442.510174084351;5352.732363381815;5376.78492860289;5388.595855310789;5390.9191362129595;5393.325834270716;5388.434214407509;5389.083855744533;5389.164679474688;5391.487808658249;5409.404965453797;4276.593723596254;4288.120935208275;4301.827926882889;4303.268444364466;4343.739375637472;3664.3221004114325;3713.628637136265;3724.7020238380865;3724.7951362675312;3759.379702349082;4736.692115012514;4752.168370535212;4757.405324334458;4785.880352991134;4804.735739534359;5540.252065971043;5588.776448941997;5591.189277554732;5593.74828132735;5647.689377084218;4619.976900115478;4690.95309046912;4733.219018861918;4750.667453278237;4755.38180036593;4926.113299606033;4976.676516026447;5007.298978143063;5012.47369026247;5021.14640511114;5124.692518448905;5130.333056702605;5147.536721695065;5152.355955505562;5182.378114626269;5360.65155764874;5544.445555444585;5545.972270138624;5546.168074788814;5554.055810512142;5120.283160357479;5124.846254612367;5151.201563757623;5211.088059589632;5220.895582088413;5499.285092937893;5505.201745746925;5514.641548299348;5516.696581688046;5560.777568897361;5535.169724541351;5594;5595.524380427709;5597.720113994365;5603.495685388335;5210.5831533477985;5338.8932221356135;5342.813001544927;5347.7593508293;5471.288732464914;5449.209864569694;5450.91823622638;5457.45425457467;5458.208559758733;5459.672419654833;5340.893182136413;5371.892562148657;5376.731163441734;5381.19282107687;5389.407165211902;5392.568594512508;5439.782408703766;5445.918311225414;5446.046941785093;5449.128139497661;5233.921491177712;5298.8940221196135;5300.496452836975;5302.522772950453;5356.303680521507 18:43:11 INFO - PID 3405 | |1;ext.html;617.1914791622975;664.1266734244468;681.3288910423232;989.3470309595664;998.8851282102561;26031.73968260317;32482;32940.50589241163;33041.50437743436;33052.66947330526;34249.48625770616;34425.31149377011;34462.65537344626;34502.48246276308;34665;5217.582593392539;6744.662766861649;6900.03399524067;6922.480813938974;6922.5154239203275;5305.495977882095;5397.595180361469;5406.16204488303;6109.053096770008;6763.458923286125;6851.143607049119;6891.207511136208;6897.034415181878;6901.6204108774;6901.8964715529055;6817.5227734058635;6869.656517174146;6879.89680154798;6881.380675739208;6888.552244104118;5221.895562088775;5349.438308977579;6698.330166983312;6969;7041.36627703504;31022.534661980088;31236.687633123605;31280.061598152093;31307.373852522822;31336.68663313383;2989.820610763361;3023.818570885754;3024.2892920163745;3024.3951209757915;3027.8334691591954;24170.758292417202;24213;24240.878795606084;24248.757512424825;24281.271561853173;3103.6896310368784;3112.937741245162;3129.7809153359276;3143.151349135743;3143.2770462793455;141479;141604;141634.29182854225;141774.29112854472;141838;38930.41604375937;39152.41271380932;39226.215475690326;39226.215475690326;39266.41100383497;41940.161196776506;42009.78995105005;42025.78987105045;42027.22496338187;42069.36895946563;10981.560737570568;11020.283681560673;11050.778984420267;11057.889421105687;11071.05895998833;8630.180132887366;8638.913610863936;8642.43824151428;8660.090690477535;8660.78348041304;10183.592656293813;10368.481575921174;11451.370174640475;11725.179237453296;11829.704257393634;4795.592374648194;4846.321514987904;4874.731889746098;4933.703977761274;4951.405831300267;876.6887754847065;895.6596493332622;905.7328088213993;926.9073092690677;928.7167413938679;20446.284380046625;20692.896535517226;20830.37508874736;21158.78841211599;21306;966.246327864267;974.2108891797667;991.6281394477071;994.5077186792583;997.1324947295899;10274.845877311845;10909.34543927367;10948.671539853816;11023.283486573348;11064.05955493776;158253.2087339579;158737;158817;158896;159399;38605.613943860764;38606.42090368647;38680.22639547193;38683.41974870379;38806.80596596999;1186.727052777866;1211.2611307102661;1216.2459275249403;1216.416120262279;1225.5649244518247;19436.61126777485;19796.80203198007;20317.49206269855;20332.390028299174;20757.481062973548;28950.710492894803;29057.273568160967;29075.273118172216;29079.418411631224;29110.854445727637;145582;145772;146031;146056;146422.26788865987;28269.43461130807;28293.292667683472;28367.432651346444;28369.751595954815;28410.153340032834;29053.12840614785;29641.5553766698;32535.837320813243;33540.49689254614;34200.82899585586;26036.479270414864;26425.471490569696;26426.603600946386;26438.735612644396;26612.600810987464;25862.74137258603;26079.478410432064;26093;27066.458670826865;27367.589486158122;29119.563206552342;29168.7083129166;29199.562006569493;29206.7079329204;29250.853745731987;24869.502609947336;25346.619800702636;25450.618240726773;25459.49081018332;26370.20889373322;20365.898170509052;21158.259460918795;21294.893525532272;22145.446363841034;22922.77077229273 18:43:11 INFO - PID 3405 | |2;jquery.html;411.5740208883804;415.57196088029343;418.48735299258414;432.2155288152004;442.0694438207573;16227.675446491063;17025.233864476097;17619.91190044051;17834.732479012808;17839.55401114973;17361;17678.82321176791;17879;18576.16407261673;18741.15664795084;5440.945590544104;5470.234167216593;5562.332520097594;5583.218349431083;5651.18057881609;5510.283663123788;5645.971770141144;5654.434556544334;5657.688827114507;5659.377468478464;5377.542908852753;5564.304461942257;5612.214289999403;5613.46672066153;5620.297462817148;5550.555955523549;5578.832635020955;5586.273784408041;5595.272614560102;5596.2165296858475;4977.904860930589;4987.800487980477;5010.323606313137;5039.6220283478515;5052.343195384577;22030.779692203032;22320.665190022162;22637.660435093487;22774.886125569432;22970.31089067314;1609.5022152982103;1667.2997341116638;1672.030222470964;1674.6901823162755;1676.706576349134;14260.073095248776;14683.77974330386;14716.299673747106;14841;14898.702025959528;2006.9197232110698;2059.6292667319876;2103.684447332897;2103.7896210378995;2113.0702490904105;69038;86708.56645716731;87879.56060219722;89749.10250897473;91462.54268728614;10013.749656258506;10516.842247366296;10742.516586753614;11008.889911100787;11096.611618593472;14571.41714331436;14577;14577.198254095914;14616.561503154922;14637.341319640642;7083.114610673666;7220.530665506725;7365.594892280976;7429.4799364044;7437.181909989897;6415.775447859396;6535.248446428626;6623.768168114093;6624.403803657694;6649.368310010541;6725.831854203684;6786.728530858711;6799.830004249834;6828;6954.687039083254;1981.564055907698;1981.920723171086;1986.7119267706103;2009.196321471423;2057.6193404220267;826.3059030414481;859.5702148925537;876.0451108291865;881.8104107616894;894.6376717429322;17087.316507339816;17385.13074346303;17506.38727644552;17800;17942.730859037125;856.8243510080418;857.566928701002;864.4769914201943;871.847426700325;877.4735158904733;9157.267418606496;9350.812983740423;9695.660651877291;9808.21534277256;9955.303128780915;356533;356883;357910;359739;366348;73273.6336318315;73569;74259;74378.62810685912;74565.62717186379;1033.34382667007;1041.7083216699336;1060.596973150198;1101.0805977009127;1108.8115020446382;17194.226259818588;17554.067405861988;18016;18188.181531830836;18379.448616541526;38572.45710171562;38658;38686.613133868304;39562.40656390214;40085.99785005398;48495.75752121217;48534;48695.26957095717;48834;48896.2665560024;8107.594620268845;8256.876146857921;8344;8467.95766021166;8487.957560212159;12293.815592766294;12503.437345319664;12825.935870320962;12910.80633790475;12928.935355323163;10997.230193886351;11298.774024519627;11482.82775758381;11623;11702.473388697359;9956.751081223027;10009.149222315893;10075.748106297402;10084.596616135272;10149.34029288094;11032.227744057838;11068.557257709923;11108.38903860263;11579.768404631692;11601.477933492844;8030.478018928751;8038.155993620586;8247.175282471704;8270.172982701682;8457.40798144124;11079.501422436171;11176.329420234812;11191;11395.202335836411;11453 18:43:11 INFO - PID 3405 | |3;mootools.html;1261.6656586004708;1298.2145801789916;1306.7843805772047;1314.3822403470372;1317.4928797625919;9813.803723925517;10251.077403033725;10257.589696412144;10261.640842570512;10298.999999999982;9569.904300957005;10331.741706457342;10349.844752328721;10362.948185259082;10374.066334029954;2286.234111572621;2321.5240875620543;2325.6395258734924;2326.0463210083867;2327.2320134355705;1881.1158755384947;2231.8326125540566;2300.5973954557967;2304.2050492580033;2309.191782875997;1833.8624603154751;2124;2260.2541161416693;2275.9317220483413;2287.2109122352763;2223.5775202711516;2227.0646328542052;2228.8662680239236;2229.9665505017356;2232.162938897913;2130.0414813333905;2152.6448136057534;2165.6426689596196;2169.8372622053253;2179.1174574297434;9973.80052398948;10157.28898977072;10182.338148020355;10186.388816671024;10188.745281368025;5681.318241811009;5899;5901.2918449785875;5902.468777810018;5902.498287645555;3229.8708051677904;3502.2820321833965;3528.4707293905863;3548.095235714902;3553.928921421557;1073.495457135153;1095.3920574081383;1113.142879982417;1146.1633007904277;1146.5643055638805;14951.850481495154;15085.396584136732;15096.471623493126;15097.54707358781;15102.471413500585;4670.276107203389;4698.8355407560575;4887.0714564232685;4952.90094198114;4977.0792403405485;10797.514111864984;10807.189460790314;10810.67567972962;10820.567177312976;10822;5402.540784033401;5423.29497165366;5429.348478182644;5436.972815135899;5439.3200849893765;5059.241113832954;5108.335916330853;5119.718415487109;5156.767945442464;5180.300659411007;3853.2293541291856;3950.9012274693364;3968.464257325282;3990.880273591797;4098.979505102515;1157.6932112990064;1159.4898244772273;1161.9302841829517;1174.8883856033663;1177.1818586082761;649.7076315657973;669.3841665667597;669.517947078103;670.1622971285892;696.1889398850384;4974.825881094144;5042.571381432619;5139.820106296262;5206.0368831766245;6028.819135425944;824.2993455562723;833.5498830631391;839.6935118681637;845.4927043773687;851.7529916324197;3632.54593175853;3653.415453527423;3723.106454450912;3753.0992561784974;3790.0524868782804;13132.671683207805;13453;13578.321083945762;13579.321033948263;13598.796018059737;12976.610701678965;13182.472701091849;13397.866021339662;13406.530771422953;13408.865911340958;6994.4404447644065;7118.60847653384;7218.27817218274;7221.313975172246;7283.635818209174;5648.915266271091;5875.41245875409;5892.882142357214;6063.72713227897;6155.045967874987;11472.311661300015;11709.76580468403;11758.941205293919;11823.527058917547;12009.699757506132;11657.242279251821;12316.753664926831;12348.197367171106;12459.377031148226;12459.813102803284;8309.6676132954;8460.788480288042;8472.406931514735;8474.110218426977;8476.279516240942;8818.51498167607;9564.713058608253;9590.616375345187;9627.422354658744;9684.903150968401;8122.796930076795;8141.14517975604;8249.133840946613;8282.254597086292;8320.209580089882;7538.811529711801;7598.620068996417;7639.350655194148;7689.692412303445;7751.30238278535;7158.105236845395;7267.454940879349;7324.011258480037;7344.118705755343;7517;5070.391553013515;5114.71869047206;5185.792568297376;5244.475552444877;5328.866778330417;5302.575793936553;5325.973370133124;5404.189371594135;5423.810166644149;5500 18:43:11 INFO - PID 3405 | |4;prototype.html;363.56009228833113;383.5991388998498;386.87619961612296;390.7303960267415;392.3095352180163;5033.471485494016;5178.326817513727;5294.020606187853;5329.866753331174;5369.838904832852;5023.698578085317;5185.325907632012;5197.6101792365625;5205.531502164805;5212.76542555585;4105.4252404663375;4114.074333275004;4115.73247738896;4124.072083781155;4124.690648201382;4074.97962510187;4085.407615895693;4113.012876909535;4129.864287320996;4130.566290539495;4073.2464494068545;4074.124063326384;4084.9387259191135;4085.4893138357706;4115;4058.715889887709;4060.106776509193;4073.7352072115364;4088.5093788745244;4127.587241275878;3692.2246328271094;3697.574778900434;3698.1124530112847;3721.9813900930594;3739.0652336915773;4887.975560122212;5086.516780905845;5094.745262736886;5108.719020453874;5117.232415137722;4608.700434471749;4613.100445413146;4616.53834616539;4618.5843274105155;4618.766863195743;5349.518543331119;5476.754530642457;5523.4200408956885;5527.309086364205;5535.667859928417;3844.0966372902344;3849.3263678856088;3856.9228615427814;3857.9421308680394;3871.5741268460447;5815.941840581582;5862.120681897707;5867.890018290271;5876.706164691791;5880.558958078119;3892.8442862285756;3906.7851268179957;3916.8824935251987;3937.448757173998;3940;3799.2591444668024;3942.250972315251;3964.5440774311323;3984.7409918355793;3989.2819292527624;4562.680612357103;4567.657425693086;4574.382458368144;4582.381378513925;4583.587477127075;5495.53287970519;5507.118860982224;5522.779088836482;5531.668099914018;5532.059549876531;1259.4206664934234;1291.4834066373526;1469.6252455623749;1519.369461673393;1530.2731202678638;1067.3702515515727;1092.8415379770047;1096.8245080787035;1098.5056724474018;1105.657246253664;4765.737884416321;4774.809007639656;4780.211265141283;4798.568128868418;4803.351547541107;5678.602497825113;5693.430656934273;5693.800716974886;5695.629784064023;5730.36965933747;4703.788329525111;4764.2615394613895;4766.95233047672;4798.304245884327;4807.18277892759;2987.4473222453917;3002.8348440835525;3011.9548206776917;3013.2316259353724;3013.3069394039494;5262.447443018505;5270.736463176826;5279.86482906177;5291.576673866081;5302.708351040653;5639.984802735547;5800.216970708984;5819.505342045889;5838.299404071538;5842.853928651818;5255.894882102337;5261.579073674096;5273.235380869753;5274.736263186748;5287;5528.364238112668;5675.353023741403;5684.232628595252;5705.315362156568;5713.428657134254;5591.720413979366;5605.859853503532;5606.355269144101;5610.90587335482;5623.8189980104635;5280.894382112413;5292.9206061908335;5297.443768404262;5299.549538289134;5306.28365170697;4215.533912661128;4274.380214868765;4311.719738217006;4313.266744653353;4315.346062171679;5433.212184233343;5585;5655.123455864347;5666.376698563073;5702.344230413555;5250.65870718402;5476.780928762964;5484.095124304445;5493.176023596333;5506.779728810803;5490.6431081979545;5491.066518691752;5493.285872836505;5497.615166938276;5506.256655351476;5307.9734601326745;5317.57459403239;5402.918956215739;5453.509184173444;5465.644733092496;5504.834854954358;5526.806561770319;5535.308086489189;5545.72271386421;5561.4716601922755;5257.5005374489365;5260.9736951315;5326.36083669962;5364.14173732201;5367.919481207863 18:43:11 INFO - PID 3405 | |5;yui.html;739.5377888819488;750.4634186556615;750.6847124207832;761.6153842309633;772.8222508822969;12386.81419778703;12955.546555870547;13013.544525941583;13063.934680326585;13071.281079540646;13107.34463276837;13346.132501387427;13371.59885203443;13383.99620028497;13858.307084645752;892.2594246775177;911.1253196930954;913.5477938420491;917.8485549884294;918.2837386838277;913.3880300198882;918.9494577798218;920.2821798996794;920.9907900920972;926.819270242303;966.1980556138388;966.5264020629876;967.8790151231096;968.1915600473613;969.0261287406147;954.4511905654276;955.3408148377666;961.0917682789767;961.6297725375711;962.3792653738335;689.4691087862318;690.6788343420284;693.3066933066933;693.6011793218869;693.8369483171501;15079.54761357161;15242.542723718307;15434.691306173925;15441.459548915735;15472.303746331441;14447.205403702792;14519.564413067625;14656.560303190921;14665.48670796517;14675.97268191227;11619.767604647945;11751.294922304689;11928.58249961256;11971.521139154424;11991.400429978466;3063.2801291696433;3067.5245336972803;3095.9535606965915;3101;3102.519109538025;61357.38642613606;62770.68614656943;70350;71321;72532;12348.814767778491;12667.746645067231;12900.35498225085;12952.611421657366;12982.805257921138;21996.120155193934;22261.443463913532;22711;22734.317970460914;22770.31689049331;6163.1679723237685;6227.782027629011;6246.281677607042;6246.312905580383;6247.094171345129;3153.1486498645236;3200.087974927181;3204.983975080156;3212.855421506038;3215.871365145415;3608.079939615434;4055.9594404055583;4138.420621113047;4153.854615088458;4154.231467178582;773.1456740301998;786.9331106855981;801.4870482890955;802.0335495727634;803.3613277444446;591.7189335065809;602.3284038297267;611.6207951070364;620.1999420747276;626.2453743239386;9699.95602286807;10149.98500149979;10388.844167337496;10859.18556108295;10999.23005389615;600.3353939887053;611.339753066687;612.5252928980076;620.1101419463085;625.6402568523117;4613.63090952723;4783.162946484351;4785.377900872864;4881.536254055859;4955.752212389366;61573.384266157664;61829.38170618326;62182.68908655518;62230.68884655548;63607.68196158989;54693;56959.71520142373;57065.42934570684;57205.606872941054;57759.4224057754;35066.824665877015;36690.63309366872;36826.63173368232;45971.540284597395;51014.74492627513;9670.274729395183;10308.381497110197;10411;10605.628802991858;10718.571257149626;24778.380540485912;25154.874225628755;25408.745912541377;25667.871660641576;26405;30896;30906.690933091282;31191.68808311888;31225.687743123188;31905.20236994001;13393;13548.59354219375;13647.727045458836;13764.105333153313;13848.30758462093;14852.257387130816;15277.236138193268;15453.845461545241;15538.611534711723;15545.145017024171;13921.025528212927;14479.638009049859;14864.554063378117;15132.773008404663;15141.470048548248;13807.723845523235;14131.64670883236;14589.781153282922;14596.708065838837;14879.92560037193;11993.520259189534;12187.878121218675;12412.503499860266;13538.255395953318;13854.722905541632;9773.804523909625;9787.314887957775;10010.8498372523;10132.898671013196;10551.94724026375;10093.243006774668;10724.410157441103;11155.442227888736;11259.49332280033;11477.426128693365 18:43:11 INFO - PID 3405 | __end_tp_report 18:43:11 INFO - PID 3405 | __start_cc_report 18:43:11 INFO - PID 3405 | _x_x_mozilla_cycle_collect,1603 18:43:11 INFO - PID 3405 | __end_cc_report 18:43:11 INFO - PID 3405 | __startTimestamp1494553391815__endTimestamp 18:43:11 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | ------- Summary: start ------- 18:43:11 INFO - PID 3405 | Number of tests: 6 18:43:11 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | [#0] dojo.html Cycles:165 Average:5037.41 Median:5204.48 stddev:617.67 (11.9%) stddev-sans-first:591.36 18:43:11 INFO - PID 3405 | Values: 2685.3 2711.1 2743.6 2747.1 2748.1 4860.0 5044.3 5132.5 5163.7 5204.5 5102.6 5113.8 5155.6 5175.2 5190.7 4232.5 4435.9 4544.3 4608.6 4613.2 4475.0 4556.6 4576.4 4617.1 4634.6 4530.5 4531.7 4547.0 4556.1 4560.9 4535.9 4537.4 4542.1 4547.5 4555.7 4500.2 4501.8 4515.9 4517.5 4520.8 5024.7 5082.1 5120.3 5172.0 5182.0 5571.6 5584.2 5585.1 5585.3 5587.9 5587.9 5620.1 5633.5 5649.8 5707.0 5049.1 5112.2 5112.3 5116.6 5182.4 5703.1 5728.2 5738.7 5745.5 5799.4 5255.4 5329.3 5330.5 5332.4 5335.8 5406.4 5415.2 5421.8 5433.9 5442.5 5352.7 5376.8 5388.6 5390.9 5393.3 5388.4 5389.1 5389.2 5391.5 5409.4 4276.6 4288.1 4301.8 4303.3 4343.7 3664.3 3713.6 3724.7 3724.8 3759.4 4736.7 4752.2 4757.4 4785.9 4804.7 5540.3 5588.8 5591.2 5593.7 5647.7 4620.0 4691.0 4733.2 4750.7 4755.4 4926.1 4976.7 5007.3 5012.5 5021.1 5124.7 5130.3 5147.5 5152.4 5182.4 5360.7 5544.4 5546.0 5546.2 5554.1 5120.3 5124.8 5151.2 5211.1 5220.9 5499.3 5505.2 5514.6 5516.7 5560.8 5535.2 5594.0 5595.5 5597.7 5603.5 5210.6 5338.9 5342.8 5347.8 5471.3 5449.2 5450.9 5457.5 5458.2 5459.7 5340.9 5371.9 5376.7 5381.2 5389.4 5392.6 5439.8 5445.9 5446.0 5449.1 5233.9 5298.9 5300.5 5302.5 5356.3 18:43:11 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | [#1] ext.html Cycles:180 Average:28778.01 Median:3024.34 stddev:38440.56 (1271.0%) stddev-sans-first:38490.23 18:43:11 INFO - PID 3405 | Values: 617.2 664.1 681.3 989.3 998.9 26031.7 32482.0 32940.5 33041.5 33052.7 34249.5 34425.3 34462.7 34502.5 34665.0 5217.6 6744.7 6900.0 6922.5 6922.5 5305.5 5397.6 5406.2 6109.1 6763.5 6851.1 6891.2 6897.0 6901.6 6901.9 6817.5 6869.7 6879.9 6881.4 6888.6 5221.9 5349.4 6698.3 6969.0 7041.4 31022.5 31236.7 31280.1 31307.4 31336.7 2989.8 3023.8 3024.3 3024.4 3027.8 24170.8 24213.0 24240.9 24248.8 24281.3 3103.7 3112.9 3129.8 3143.2 3143.3 141479.0 141604.0 141634.3 141774.3 141838.0 38930.4 39152.4 39226.2 39226.2 39266.4 41940.2 42009.8 42025.8 42027.2 42069.4 10981.6 11020.3 11050.8 11057.9 11071.1 8630.2 8638.9 8642.4 8660.1 8660.8 10183.6 10368.5 11451.4 11725.2 11829.7 4795.6 4846.3 4874.7 4933.7 4951.4 876.7 895.7 905.7 926.9 928.7 20446.3 20692.9 20830.4 21158.8 21306.0 966.2 974.2 991.6 994.5 997.1 10274.8 10909.3 10948.7 11023.3 11064.1 158253.2 158737.0 158817.0 158896.0 159399.0 38605.6 38606.4 38680.2 38683.4 38806.8 1186.7 1211.3 1216.2 1216.4 1225.6 19436.6 19796.8 20317.5 20332.4 20757.5 28950.7 29057.3 29075.3 29079.4 29110.9 145582.0 145772.0 146031.0 146056.0 146422.3 28269.4 28293.3 28367.4 28369.8 28410.2 29053.1 29641.6 32535.8 33540.5 34200.8 26036.5 26425.5 26426.6 26438.7 26612.6 25862.7 26079.5 26093.0 27066.5 27367.6 29119.6 29168.7 29199.6 29206.7 29250.9 24869.5 25346.6 25450.6 25459.5 26370.2 20365.9 21158.3 21294.9 22145.4 22922.8 18:43:11 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | [#2] jquery.html Cycles:180 Average:24426.47 Median:38672.31 stddev:59774.32 (154.6%) stddev-sans-first:59914.81 18:43:11 INFO - PID 3405 | Values: 411.6 415.6 418.5 432.2 442.1 16227.7 17025.2 17619.9 17834.7 17839.6 17361.0 17678.8 17879.0 18576.2 18741.2 5440.9 5470.2 5562.3 5583.2 5651.2 5510.3 5646.0 5654.4 5657.7 5659.4 5377.5 5564.3 5612.2 5613.5 5620.3 5550.6 5578.8 5586.3 5595.3 5596.2 4977.9 4987.8 5010.3 5039.6 5052.3 22030.8 22320.7 22637.7 22774.9 22970.3 1609.5 1667.3 1672.0 1674.7 1676.7 14260.1 14683.8 14716.3 14841.0 14898.7 2006.9 2059.6 2103.7 2103.8 2113.1 69038.0 86708.6 87879.6 89749.1 91462.5 10013.7 10516.8 10742.5 11008.9 11096.6 14571.4 14577.0 14577.2 14616.6 14637.3 7083.1 7220.5 7365.6 7429.5 7437.2 6415.8 6535.2 6623.8 6624.4 6649.4 6725.8 6786.7 6799.8 6828.0 6954.7 1981.6 1981.9 1986.7 2009.2 2057.6 826.3 859.6 876.0 881.8 894.6 17087.3 17385.1 17506.4 17800.0 17942.7 856.8 857.6 864.5 871.8 877.5 9157.3 9350.8 9695.7 9808.2 9955.3 356533.0 356883.0 357910.0 359739.0 366348.0 73273.6 73569.0 74259.0 74378.6 74565.6 1033.3 1041.7 1060.6 1101.1 1108.8 17194.2 17554.1 18016.0 18188.2 18379.4 38572.5 38658.0 38686.6 39562.4 40086.0 48495.8 48534.0 48695.3 48834.0 48896.3 8107.6 8256.9 8344.0 8468.0 8488.0 12293.8 12503.4 12825.9 12910.8 12928.9 10997.2 11298.8 11482.8 11623.0 11702.5 9956.8 10009.1 10075.7 10084.6 10149.3 11032.2 11068.6 11108.4 11579.8 11601.5 8030.5 8038.2 8247.2 8270.2 8457.4 11079.5 11176.3 11191.0 11395.2 11453.0 18:43:11 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | [#3] mootools.html Cycles:180 Average:6270.36 Median:3771.58 stddev:4028.59 (106.8%) stddev-sans-first:4022.31 18:43:11 INFO - PID 3405 | Values: 1261.7 1298.2 1306.8 1314.4 1317.5 9813.8 10251.1 10257.6 10261.6 10299.0 9569.9 10331.7 10349.8 10362.9 10374.1 2286.2 2321.5 2325.6 2326.0 2327.2 1881.1 2231.8 2300.6 2304.2 2309.2 1833.9 2124.0 2260.3 2275.9 2287.2 2223.6 2227.1 2228.9 2230.0 2232.2 2130.0 2152.6 2165.6 2169.8 2179.1 9973.8 10157.3 10182.3 10186.4 10188.7 5681.3 5899.0 5901.3 5902.5 5902.5 3229.9 3502.3 3528.5 3548.1 3553.9 1073.5 1095.4 1113.1 1146.2 1146.6 14951.9 15085.4 15096.5 15097.5 15102.5 4670.3 4698.8 4887.1 4952.9 4977.1 10797.5 10807.2 10810.7 10820.6 10822.0 5402.5 5423.3 5429.3 5437.0 5439.3 5059.2 5108.3 5119.7 5156.8 5180.3 3853.2 3950.9 3968.5 3990.9 4099.0 1157.7 1159.5 1161.9 1174.9 1177.2 649.7 669.4 669.5 670.2 696.2 4974.8 5042.6 5139.8 5206.0 6028.8 824.3 833.5 839.7 845.5 851.8 3632.5 3653.4 3723.1 3753.1 3790.1 13132.7 13453.0 13578.3 13579.3 13598.8 12976.6 13182.5 13397.9 13406.5 13408.9 6994.4 7118.6 7218.3 7221.3 7283.6 5648.9 5875.4 5892.9 6063.7 6155.0 11472.3 11709.8 11758.9 11823.5 12009.7 11657.2 12316.8 12348.2 12459.4 12459.8 8309.7 8460.8 8472.4 8474.1 8476.3 8818.5 9564.7 9590.6 9627.4 9684.9 8122.8 8141.1 8249.1 8282.3 8320.2 7538.8 7598.6 7639.4 7689.7 7751.3 7158.1 7267.5 7324.0 7344.1 7517.0 5070.4 5114.7 5185.8 5244.5 5328.9 5302.6 5326.0 5404.2 5423.8 5500.0 18:43:11 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | [#4] prototype.html Cycles:180 Average:4568.09 Median:5112.98 stddev:1306.42 (25.6%) stddev-sans-first:1271.40 18:43:11 INFO - PID 3405 | Values: 363.6 383.6 386.9 390.7 392.3 5033.5 5178.3 5294.0 5329.9 5369.8 5023.7 5185.3 5197.6 5205.5 5212.8 4105.4 4114.1 4115.7 4124.1 4124.7 4075.0 4085.4 4113.0 4129.9 4130.6 4073.2 4074.1 4084.9 4085.5 4115.0 4058.7 4060.1 4073.7 4088.5 4127.6 3692.2 3697.6 3698.1 3722.0 3739.1 4888.0 5086.5 5094.7 5108.7 5117.2 4608.7 4613.1 4616.5 4618.6 4618.8 5349.5 5476.8 5523.4 5527.3 5535.7 3844.1 3849.3 3856.9 3857.9 3871.6 5815.9 5862.1 5867.9 5876.7 5880.6 3892.8 3906.8 3916.9 3937.4 3940.0 3799.3 3942.3 3964.5 3984.7 3989.3 4562.7 4567.7 4574.4 4582.4 4583.6 5495.5 5507.1 5522.8 5531.7 5532.1 1259.4 1291.5 1469.6 1519.4 1530.3 1067.4 1092.8 1096.8 1098.5 1105.7 4765.7 4774.8 4780.2 4798.6 4803.4 5678.6 5693.4 5693.8 5695.6 5730.4 4703.8 4764.3 4767.0 4798.3 4807.2 2987.4 3002.8 3012.0 3013.2 3013.3 5262.4 5270.7 5279.9 5291.6 5302.7 5640.0 5800.2 5819.5 5838.3 5842.9 5255.9 5261.6 5273.2 5274.7 5287.0 5528.4 5675.4 5684.2 5705.3 5713.4 5591.7 5605.9 5606.4 5610.9 5623.8 5280.9 5292.9 5297.4 5299.5 5306.3 4215.5 4274.4 4311.7 4313.3 4315.3 5433.2 5585.0 5655.1 5666.4 5702.3 5250.7 5476.8 5484.1 5493.2 5506.8 5490.6 5491.1 5493.3 5497.6 5506.3 5308.0 5317.6 5402.9 5453.5 5465.6 5504.8 5526.8 5535.3 5545.7 5561.5 5257.5 5261.0 5326.4 5364.1 5367.9 18:43:11 INFO - PID 3405 | 18:43:11 INFO - PID 3405 | [#5] yui.html Cycles:180 Average:14696.10 Median:17529.18 stddev:17072.30 (97.4%) stddev-sans-first:17088.02 18:43:11 INFO - PID 3405 | Values: 739.5 750.5 750.7 761.6 772.8 12386.8 12955.5 13013.5 13063.9 13071.3 13107.3 13346.1 13371.6 13384.0 13858.3 892.3 911.1 913.5 917.8 918.3 913.4 918.9 920.3 921.0 926.8 966.2 966.5 967.9 968.2 969.0 954.5 955.3 961.1 961.6 962.4 689.5 690.7 693.3 693.6 693.8 15079.5 15242.5 15434.7 15441.5 15472.3 14447.2 14519.6 14656.6 14665.5 14676.0 11619.8 11751.3 11928.6 11971.5 11991.4 3063.3 3067.5 3096.0 3101.0 3102.5 61357.4 62770.7 70350.0 71321.0 72532.0 12348.8 12667.7 12900.4 12952.6 12982.8 21996.1 22261.4 22711.0 22734.3 22770.3 6163.2 6227.8 6246.3 6246.3 6247.1 3153.1 3200.1 3205.0 3212.9 3215.9 3608.1 4056.0 4138.4 4153.9 4154.2 773.1 786.9 801.5 802.0 803.4 591.7 602.3 611.6 620.2 626.2 9700.0 10150.0 10388.8 10859.2 10999.2 600.3 611.3 612.5 620.1 625.6 4613.6 4783.2 4785.4 4881.5 4955.8 61573.4 61829.4 62182.7 62230.7 63607.7 54693.0 56959.7 57065.4 57205.6 57759.4 35066.8 36690.6 36826.6 45971.5 51014.7 9670.3 10308.4 10411.0 10605.6 10718.6 24778.4 25154.9 25408.7 25667.9 26405.0 30896.0 30906.7 31191.7 31225.7 31905.2 13393.0 13548.6 13647.7 13764.1 13848.3 14852.3 15277.2 15453.8 15538.6 15545.1 13921.0 14479.6 14864.6 15132.8 15141.5 13807.7 14131.6 14589.8 14596.7 14879.9 11993.5 12187.9 12412.5 13538.3 13854.7 9773.8 9787.3 10010.8 10132.9 10551.9 10093.2 10724.4 11155.4 11259.5 11477.4 18:43:11 INFO - PID 3405 | -------- Summary: end -------- 18:43:11 INFO - PID 3405 | 18:43:12 INFO - TEST-INFO | 3405: exit 0 18:43:12 INFO - TEST-OK | dromaeo_css | took 1122365ms 18:43:12 INFO - TEST-START | kraken 18:43:12 INFO - operating with platform_type : linux_ 18:43:12 INFO - Initialising browser for kraken test... 18:43:12 INFO - TEST-INFO | started process 3729 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJnNC_n/profile http://localhost:46533/getInfo.html) 18:43:16 INFO - PID 3729 | __metrics Screen width/height:1600/1200 18:43:16 INFO - PID 3729 | colorDepth:24 18:43:16 INFO - PID 3729 | Browser inner width/height: 1024/695 18:43:16 INFO - PID 3729 | __metrics 18:43:17 INFO - PID 3729 | 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" 18:43:17 INFO - PID 3729 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:43:18 INFO - TEST-INFO | 3729: exit 0 18:43:18 INFO - Browser initialized. 18:43:18 INFO - Running cycle 1/1 for kraken test... 18:43:18 INFO - TEST-INFO | started process 3824 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJnNC_n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:30 INFO - PID 3824 | RSS: Main: 148852736 18:43:30 INFO - PID 3824 | 18:43:33 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46533/tests/kraken/driver.html?testName=audio-beat-detection) 18:43:34 INFO - PID 3824 | RSS: Main: 221110272 18:43:34 INFO - PID 3824 | 18:43:36 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46533/tests/kraken/driver.html?testName=audio-dft) 18:43:37 INFO - PID 3824 | RSS: Main: 182018048 18:43:37 INFO - PID 3824 | 18:43:40 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46533/tests/kraken/driver.html?testName=audio-fft) 18:43:41 INFO - PID 3824 | RSS: Main: 264564736 18:43:41 INFO - PID 3824 | 18:43:43 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46533/tests/kraken/driver.html?testName=audio-oscillator) 18:43:43 INFO - PID 3824 | RSS: Main: 175845376 18:43:43 INFO - PID 3824 | 18:43:45 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46533/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:43:46 INFO - PID 3824 | RSS: Main: 178135040 18:43:46 INFO - PID 3824 | 18:43:49 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46533/tests/kraken/driver.html?testName=imaging-darkroom) 18:43:50 INFO - PID 3824 | RSS: Main: 238284800 18:43:50 INFO - PID 3824 | 18:43:53 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46533/tests/kraken/driver.html?testName=imaging-desaturate) 18:43:54 INFO - PID 3824 | RSS: Main: 237568000 18:43:54 INFO - PID 3824 | 18:43:57 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46533/tests/kraken/driver.html?testName=json-parse-financial) 18:43:57 INFO - PID 3824 | RSS: Main: 235126784 18:43:57 INFO - PID 3824 | 18:43:59 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46533/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:44:00 INFO - PID 3824 | RSS: Main: 199577600 18:44:00 INFO - PID 3824 | 18:44:01 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46533/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:44:02 INFO - PID 3824 | RSS: Main: 187187200 18:44:02 INFO - PID 3824 | 18:44:04 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46533/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:44:05 INFO - PID 3824 | RSS: Main: 214241280 18:44:05 INFO - PID 3824 | 18:44:07 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46533/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:44:08 INFO - PID 3824 | RSS: Main: 223764480 18:44:08 INFO - PID 3824 | 18:44:11 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46533/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:12 INFO - PID 3824 | RSS: Main: 219832320 18:44:12 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | Cycle 1(1): loaded http://localhost:46533/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46533/tests/kraken/driver.html?testName=ai-astar) 18:44:14 INFO - PID 3824 | RSS: Main: 269217792 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | __start_tp_report 18:44:14 INFO - PID 3824 | _x_x_mozilla_page_load 18:44:14 INFO - PID 3824 | _x_x_mozilla_page_load_details 18:44:14 INFO - PID 3824 | |i|pagename|runs| 18:44:14 INFO - PID 3824 | |0;ai-astar;108;113;112;114;110;112;111;112;114;111 18:44:14 INFO - PID 3824 | |1;audio-beat-detection;152;124;122;177;125;121;122;121;146;121 18:44:14 INFO - PID 3824 | |2;audio-dft;161;161;162;163;163;161;162;163;159;162 18:44:14 INFO - PID 3824 | |3;audio-fft;74;76;78;105;75;76;75;76;96;74 18:44:14 INFO - PID 3824 | |4;audio-oscillator;85;84;112;84;83;83;97;83;84;105 18:44:14 INFO - PID 3824 | |5;imaging-gaussian-blur;114;113;113;113;113;113;113;114;113;113 18:44:14 INFO - PID 3824 | |6;imaging-darkroom;149;149;149;149;148;148;149;150;148;148 18:44:14 INFO - PID 3824 | |7;imaging-desaturate;93;94;94;94;94;94;94;95;94;95 18:44:14 INFO - PID 3824 | |8;json-parse-financial;55;55;53;54;56;55;55;56;55;55 18:44:14 INFO - PID 3824 | |9;json-stringify-tinderbox;55;55;55;55;54;55;54;54;55;56 18:44:14 INFO - PID 3824 | |10;stanford-crypto-aes;82;81;83;82;81;81;82;83;81;82 18:44:14 INFO - PID 3824 | |11;stanford-crypto-ccm;133;131;139;131;131;130;137;133;183;132 18:44:14 INFO - PID 3824 | |12;stanford-crypto-pbkdf2;186;195;192;183;188;192;192;190;188;194 18:44:14 INFO - PID 3824 | |13;stanford-crypto-sha256-iterative;72;76;71;75;72;72;75;71;71;70 18:44:14 INFO - PID 3824 | __end_tp_report 18:44:14 INFO - PID 3824 | __start_cc_report 18:44:14 INFO - PID 3824 | _x_x_mozilla_cycle_collect,2005 18:44:14 INFO - PID 3824 | __end_cc_report 18:44:14 INFO - PID 3824 | __startTimestamp1494553454502__endTimestamp 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | ------- Summary: start ------- 18:44:14 INFO - PID 3824 | Number of tests: 14 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.83 (1.6%) stddev-sans-first:1.36 18:44:14 INFO - PID 3824 | Values: 108.0 113.0 112.0 114.0 110.0 112.0 111.0 112.0 114.0 111.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#1] audio-beat-detection Cycles:10 Average:133.10 Median:124.50 stddev:19.10 (15.3%) stddev-sans-first:19.00 18:44:14 INFO - PID 3824 | Values: 152.0 124.0 122.0 177.0 125.0 121.0 122.0 121.0 146.0 121.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.30 18:44:14 INFO - PID 3824 | Values: 161.0 161.0 162.0 163.0 163.0 161.0 162.0 163.0 159.0 162.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#3] audio-fft Cycles:10 Average:80.50 Median:76.00 stddev:10.81 (14.2%) stddev-sans-first:11.21 18:44:14 INFO - PID 3824 | Values: 74.0 76.0 78.0 105.0 75.0 76.0 75.0 76.0 96.0 74.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:84.00 stddev:10.74 (12.8%) stddev-sans-first:11.24 18:44:14 INFO - PID 3824 | Values: 85.0 84.0 112.0 84.0 83.0 83.0 97.0 83.0 84.0 105.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 18:44:14 INFO - PID 3824 | Values: 114.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 113.0 113.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 18:44:14 INFO - PID 3824 | Values: 149.0 149.0 149.0 149.0 148.0 148.0 149.0 150.0 148.0 148.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 18:44:14 INFO - PID 3824 | Values: 93.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.93 18:44:14 INFO - PID 3824 | Values: 55.0 55.0 53.0 54.0 56.0 55.0 55.0 56.0 55.0 55.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:55.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 18:44:14 INFO - PID 3824 | Values: 55.0 55.0 55.0 55.0 54.0 55.0 54.0 54.0 55.0 56.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:0.79 (1.0%) stddev-sans-first:0.83 18:44:14 INFO - PID 3824 | Values: 82.0 81.0 83.0 82.0 81.0 81.0 82.0 83.0 81.0 82.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:133.00 stddev:16.07 (12.1%) stddev-sans-first:16.94 18:44:14 INFO - PID 3824 | Values: 133.0 131.0 139.0 131.0 131.0 130.0 137.0 133.0 183.0 132.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.00 Median:192.00 stddev:3.74 (1.9%) stddev-sans-first:3.68 18:44:14 INFO - PID 3824 | Values: 186.0 195.0 192.0 183.0 188.0 192.0 192.0 190.0 188.0 194.0 18:44:14 INFO - PID 3824 | 18:44:14 INFO - PID 3824 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:72.00 stddev:2.07 (2.9%) stddev-sans-first:2.19 18:44:14 INFO - PID 3824 | Values: 72.0 76.0 71.0 75.0 72.0 72.0 75.0 71.0 71.0 70.0 18:44:14 INFO - PID 3824 | -------- Summary: end -------- 18:44:14 INFO - PID 3824 | 18:44:15 INFO - TEST-INFO | 3824: exit 0 18:44:15 INFO - TEST-OK | kraken | took 63008ms 18:44:15 INFO - SUITE-END | took 1185s 18:44:16 INFO - Completed test suite (00:19:45) 18:44:16 INFO - javascript benchmark 18:44:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6587.630763572174, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2685.3152446126237, 2711.0511321037625, 2743.58846173074, 2747.120921305183, 2748.065657676391, 4859.951400485994, 5044.268581055745, 5132.538071573557, 5163.7418129093585, 5204.479552044793, 5102.566281866037, 5113.821016264432, 5155.639105262633, 5175.197844334125, 5190.688558686472, 4232.513260974981, 4435.889102772424, 4544.295634176708, 4608.631309495232, 4613.192691279013, 4475.0, 4556.567126123013, 4576.427946506687, 4617.122746678137, 4634.606058485033, 4530.546945305476, 4531.682782205247, 4546.954530454719, 4556.134334476472, 4560.863174104749, 4535.931961020588, 4537.3647689323525, 4542.136993971168, 4547.499775024745, 4555.74943378114, 4500.189965806154, 4501.774911254457, 4515.864524064284, 4517.457905051382, 4520.773961301922, 5024.748762561857, 5082.059818933509, 5120.334356533664, 5171.974140129275, 5181.974090129525, 5571.609987300891, 5584.246126772874, 5585.1063829787445, 5585.301837270341, 5587.8882422351735, 5587.944120558783, 5620.072688006474, 5633.464820842013, 5649.802256921029, 5706.971465142689, 5049.065922804293, 5112.233165025276, 5112.258722485219, 5116.590672746172, 5182.378114626269, 5703.058995265735, 5728.198052272685, 5738.684372359477, 5745.482906538432, 5799.39106393831, 5255.369355677267, 5329.333833270841, 5330.520253177233, 5332.413434522199, 5335.759890804846, 5406.405110492414, 5415.241866138744, 5421.83734487966, 5433.891322173535, 5442.510174084351, 5352.732363381815, 5376.78492860289, 5388.595855310789, 5390.9191362129595, 5393.325834270716, 5388.434214407509, 5389.083855744533, 5389.164679474688, 5391.487808658249, 5409.404965453797, 4276.593723596254, 4288.120935208275, 4301.827926882889, 4303.268444364466, 4343.739375637472, 3664.3221004114325, 3713.628637136265, 3724.7020238380865, 3724.7951362675312, 3759.379702349082, 4736.692115012514, 4752.168370535212, 4757.405324334458, 4785.880352991134, 4804.735739534359, 5540.252065971043, 5588.776448941997, 5591.189277554732, 5593.74828132735, 5647.689377084218, 4619.976900115478, 4690.95309046912, 4733.219018861918, 4750.667453278237, 4755.38180036593, 4926.113299606033, 4976.676516026447, 5007.298978143063, 5012.47369026247, 5021.14640511114, 5124.692518448905, 5130.333056702605, 5147.536721695065, 5152.355955505562, 5182.378114626269, 5360.65155764874, 5544.445555444585, 5545.972270138624, 5546.168074788814, 5554.055810512142, 5120.283160357479, 5124.846254612367, 5151.201563757623, 5211.088059589632, 5220.895582088413, 5499.285092937893, 5505.201745746925, 5514.641548299348, 5516.696581688046, 5560.777568897361, 5535.169724541351, 5594.0, 5595.524380427709, 5597.720113994365, 5603.495685388335, 5210.5831533477985, 5338.8932221356135, 5342.813001544927, 5347.7593508293, 5471.288732464914, 5449.209864569694, 5450.91823622638, 5457.45425457467, 5458.208559758733, 5459.672419654833, 5340.893182136413, 5371.892562148657, 5376.731163441734, 5381.19282107687, 5389.407165211902, 5392.568594512508, 5439.782408703766, 5445.918311225414, 5446.046941785093, 5449.128139497661, 5233.921491177712, 5298.8940221196135, 5300.496452836975, 5302.522772950453, 5356.303680521507], "value": 4991.595045740208, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.1914791622975, 664.1266734244468, 681.3288910423232, 989.3470309595664, 998.8851282102561, 26031.73968260317, 32482.0, 32940.50589241163, 33041.50437743436, 33052.66947330526, 34249.48625770616, 34425.31149377011, 34462.65537344626, 34502.48246276308, 34665.0, 5217.582593392539, 6744.662766861649, 6900.03399524067, 6922.480813938974, 6922.5154239203275, 5305.495977882095, 5397.595180361469, 5406.16204488303, 6109.053096770008, 6763.458923286125, 6851.143607049119, 6891.207511136208, 6897.034415181878, 6901.6204108774, 6901.8964715529055, 6817.5227734058635, 6869.656517174146, 6879.89680154798, 6881.380675739208, 6888.552244104118, 5221.895562088775, 5349.438308977579, 6698.330166983312, 6969.0, 7041.36627703504, 31022.534661980088, 31236.687633123605, 31280.061598152093, 31307.373852522822, 31336.68663313383, 2989.820610763361, 3023.818570885754, 3024.2892920163745, 3024.3951209757915, 3027.8334691591954, 24170.758292417202, 24213.0, 24240.878795606084, 24248.757512424825, 24281.271561853173, 3103.6896310368784, 3112.937741245162, 3129.7809153359276, 3143.151349135743, 3143.2770462793455, 141479.0, 141604.0, 141634.29182854225, 141774.29112854472, 141838.0, 38930.41604375937, 39152.41271380932, 39226.215475690326, 39226.215475690326, 39266.41100383497, 41940.161196776506, 42009.78995105005, 42025.78987105045, 42027.22496338187, 42069.36895946563, 10981.560737570568, 11020.283681560673, 11050.778984420267, 11057.889421105687, 11071.05895998833, 8630.180132887366, 8638.913610863936, 8642.43824151428, 8660.090690477535, 8660.78348041304, 10183.592656293813, 10368.481575921174, 11451.370174640475, 11725.179237453296, 11829.704257393634, 4795.592374648194, 4846.321514987904, 4874.731889746098, 4933.703977761274, 4951.405831300267, 876.6887754847065, 895.6596493332622, 905.7328088213993, 926.9073092690677, 928.7167413938679, 20446.284380046625, 20692.896535517226, 20830.37508874736, 21158.78841211599, 21306.0, 966.246327864267, 974.2108891797667, 991.6281394477071, 994.5077186792583, 997.1324947295899, 10274.845877311845, 10909.34543927367, 10948.671539853816, 11023.283486573348, 11064.05955493776, 158253.2087339579, 158737.0, 158817.0, 158896.0, 159399.0, 38605.613943860764, 38606.42090368647, 38680.22639547193, 38683.41974870379, 38806.80596596999, 1186.727052777866, 1211.2611307102661, 1216.2459275249403, 1216.416120262279, 1225.5649244518247, 19436.61126777485, 19796.80203198007, 20317.49206269855, 20332.390028299174, 20757.481062973548, 28950.710492894803, 29057.273568160967, 29075.273118172216, 29079.418411631224, 29110.854445727637, 145582.0, 145772.0, 146031.0, 146056.0, 146422.26788865987, 28269.43461130807, 28293.292667683472, 28367.432651346444, 28369.751595954815, 28410.153340032834, 29053.12840614785, 29641.5553766698, 32535.837320813243, 33540.49689254614, 34200.82899585586, 26036.479270414864, 26425.471490569696, 26426.603600946386, 26438.735612644396, 26612.600810987464, 25862.74137258603, 26079.478410432064, 26093.0, 27066.458670826865, 27367.589486158122, 29119.563206552342, 29168.7083129166, 29199.562006569493, 29206.7079329204, 29250.853745731987, 24869.502609947336, 25346.619800702636, 25450.618240726773, 25459.49081018332, 26370.20889373322, 20365.898170509052, 21158.259460918795, 21294.893525532272, 22145.446363841034, 22922.77077229273], "value": 13798.940244872732, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.5740208883804, 415.57196088029343, 418.48735299258414, 432.2155288152004, 442.0694438207573, 16227.675446491063, 17025.233864476097, 17619.91190044051, 17834.732479012808, 17839.55401114973, 17361.0, 17678.82321176791, 17879.0, 18576.16407261673, 18741.15664795084, 5440.945590544104, 5470.234167216593, 5562.332520097594, 5583.218349431083, 5651.18057881609, 5510.283663123788, 5645.971770141144, 5654.434556544334, 5657.688827114507, 5659.377468478464, 5377.542908852753, 5564.304461942257, 5612.214289999403, 5613.46672066153, 5620.297462817148, 5550.555955523549, 5578.832635020955, 5586.273784408041, 5595.272614560102, 5596.2165296858475, 4977.904860930589, 4987.800487980477, 5010.323606313137, 5039.6220283478515, 5052.343195384577, 22030.779692203032, 22320.665190022162, 22637.660435093487, 22774.886125569432, 22970.31089067314, 1609.5022152982103, 1667.2997341116638, 1672.030222470964, 1674.6901823162755, 1676.706576349134, 14260.073095248776, 14683.77974330386, 14716.299673747106, 14841.0, 14898.702025959528, 2006.9197232110698, 2059.6292667319876, 2103.684447332897, 2103.7896210378995, 2113.0702490904105, 69038.0, 86708.56645716731, 87879.56060219722, 89749.10250897473, 91462.54268728614, 10013.749656258506, 10516.842247366296, 10742.516586753614, 11008.889911100787, 11096.611618593472, 14571.41714331436, 14577.0, 14577.198254095914, 14616.561503154922, 14637.341319640642, 7083.114610673666, 7220.530665506725, 7365.594892280976, 7429.4799364044, 7437.181909989897, 6415.775447859396, 6535.248446428626, 6623.768168114093, 6624.403803657694, 6649.368310010541, 6725.831854203684, 6786.728530858711, 6799.830004249834, 6828.0, 6954.687039083254, 1981.564055907698, 1981.920723171086, 1986.7119267706103, 2009.196321471423, 2057.6193404220267, 826.3059030414481, 859.5702148925537, 876.0451108291865, 881.8104107616894, 894.6376717429322, 17087.316507339816, 17385.13074346303, 17506.38727644552, 17800.0, 17942.730859037125, 856.8243510080418, 857.566928701002, 864.4769914201943, 871.847426700325, 877.4735158904733, 9157.267418606496, 9350.812983740423, 9695.660651877291, 9808.21534277256, 9955.303128780915, 356533.0, 356883.0, 357910.0, 359739.0, 366348.0, 73273.6336318315, 73569.0, 74259.0, 74378.62810685912, 74565.62717186379, 1033.34382667007, 1041.7083216699336, 1060.596973150198, 1101.0805977009127, 1108.8115020446382, 17194.226259818588, 17554.067405861988, 18016.0, 18188.181531830836, 18379.448616541526, 38572.45710171562, 38658.0, 38686.613133868304, 39562.40656390214, 40085.99785005398, 48495.75752121217, 48534.0, 48695.26957095717, 48834.0, 48896.2665560024, 8107.594620268845, 8256.876146857921, 8344.0, 8467.95766021166, 8487.957560212159, 12293.815592766294, 12503.437345319664, 12825.935870320962, 12910.80633790475, 12928.935355323163, 10997.230193886351, 11298.774024519627, 11482.82775758381, 11623.0, 11702.473388697359, 9956.751081223027, 10009.149222315893, 10075.748106297402, 10084.596616135272, 10149.34029288094, 11032.227744057838, 11068.557257709923, 11108.38903860263, 11579.768404631692, 11601.477933492844, 8030.478018928751, 8038.155993620586, 8247.175282471704, 8270.172982701682, 8457.40798144124, 11079.501422436171, 11176.329420234812, 11191.0, 11395.202335836411, 11453.0], "value": 8797.144001404062, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1261.6656586004708, 1298.2145801789916, 1306.7843805772047, 1314.3822403470372, 1317.4928797625919, 9813.803723925517, 10251.077403033725, 10257.589696412144, 10261.640842570512, 10298.999999999982, 9569.904300957005, 10331.741706457342, 10349.844752328721, 10362.948185259082, 10374.066334029954, 2286.234111572621, 2321.5240875620543, 2325.6395258734924, 2326.0463210083867, 2327.2320134355705, 1881.1158755384947, 2231.8326125540566, 2300.5973954557967, 2304.2050492580033, 2309.191782875997, 1833.8624603154751, 2124.0, 2260.2541161416693, 2275.9317220483413, 2287.2109122352763, 2223.5775202711516, 2227.0646328542052, 2228.8662680239236, 2229.9665505017356, 2232.162938897913, 2130.0414813333905, 2152.6448136057534, 2165.6426689596196, 2169.8372622053253, 2179.1174574297434, 9973.80052398948, 10157.28898977072, 10182.338148020355, 10186.388816671024, 10188.745281368025, 5681.318241811009, 5899.0, 5901.2918449785875, 5902.468777810018, 5902.498287645555, 3229.8708051677904, 3502.2820321833965, 3528.4707293905863, 3548.095235714902, 3553.928921421557, 1073.495457135153, 1095.3920574081383, 1113.142879982417, 1146.1633007904277, 1146.5643055638805, 14951.850481495154, 15085.396584136732, 15096.471623493126, 15097.54707358781, 15102.471413500585, 4670.276107203389, 4698.8355407560575, 4887.0714564232685, 4952.90094198114, 4977.0792403405485, 10797.514111864984, 10807.189460790314, 10810.67567972962, 10820.567177312976, 10822.0, 5402.540784033401, 5423.29497165366, 5429.348478182644, 5436.972815135899, 5439.3200849893765, 5059.241113832954, 5108.335916330853, 5119.718415487109, 5156.767945442464, 5180.300659411007, 3853.2293541291856, 3950.9012274693364, 3968.464257325282, 3990.880273591797, 4098.979505102515, 1157.6932112990064, 1159.4898244772273, 1161.9302841829517, 1174.8883856033663, 1177.1818586082761, 649.7076315657973, 669.3841665667597, 669.517947078103, 670.1622971285892, 696.1889398850384, 4974.825881094144, 5042.571381432619, 5139.820106296262, 5206.0368831766245, 6028.819135425944, 824.2993455562723, 833.5498830631391, 839.6935118681637, 845.4927043773687, 851.7529916324197, 3632.54593175853, 3653.415453527423, 3723.106454450912, 3753.0992561784974, 3790.0524868782804, 13132.671683207805, 13453.0, 13578.321083945762, 13579.321033948263, 13598.796018059737, 12976.610701678965, 13182.472701091849, 13397.866021339662, 13406.530771422953, 13408.865911340958, 6994.4404447644065, 7118.60847653384, 7218.27817218274, 7221.313975172246, 7283.635818209174, 5648.915266271091, 5875.41245875409, 5892.882142357214, 6063.72713227897, 6155.045967874987, 11472.311661300015, 11709.76580468403, 11758.941205293919, 11823.527058917547, 12009.699757506132, 11657.242279251821, 12316.753664926831, 12348.197367171106, 12459.377031148226, 12459.813102803284, 8309.6676132954, 8460.788480288042, 8472.406931514735, 8474.110218426977, 8476.279516240942, 8818.51498167607, 9564.713058608253, 9590.616375345187, 9627.422354658744, 9684.903150968401, 8122.796930076795, 8141.14517975604, 8249.133840946613, 8282.254597086292, 8320.209580089882, 7538.811529711801, 7598.620068996417, 7639.350655194148, 7689.692412303445, 7751.30238278535, 7158.105236845395, 7267.454940879349, 7324.011258480037, 7344.118705755343, 7517.0, 5070.391553013515, 5114.71869047206, 5185.792568297376, 5244.475552444877, 5328.866778330417, 5302.575793936553, 5325.973370133124, 5404.189371594135, 5423.810166644149, 5500.0], "value": 4736.6965535696745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.56009228833113, 383.5991388998498, 386.87619961612296, 390.7303960267415, 392.3095352180163, 5033.471485494016, 5178.326817513727, 5294.020606187853, 5329.866753331174, 5369.838904832852, 5023.698578085317, 5185.325907632012, 5197.6101792365625, 5205.531502164805, 5212.76542555585, 4105.4252404663375, 4114.074333275004, 4115.73247738896, 4124.072083781155, 4124.690648201382, 4074.97962510187, 4085.407615895693, 4113.012876909535, 4129.864287320996, 4130.566290539495, 4073.2464494068545, 4074.124063326384, 4084.9387259191135, 4085.4893138357706, 4115.0, 4058.715889887709, 4060.106776509193, 4073.7352072115364, 4088.5093788745244, 4127.587241275878, 3692.2246328271094, 3697.574778900434, 3698.1124530112847, 3721.9813900930594, 3739.0652336915773, 4887.975560122212, 5086.516780905845, 5094.745262736886, 5108.719020453874, 5117.232415137722, 4608.700434471749, 4613.100445413146, 4616.53834616539, 4618.5843274105155, 4618.766863195743, 5349.518543331119, 5476.754530642457, 5523.4200408956885, 5527.309086364205, 5535.667859928417, 3844.0966372902344, 3849.3263678856088, 3856.9228615427814, 3857.9421308680394, 3871.5741268460447, 5815.941840581582, 5862.120681897707, 5867.890018290271, 5876.706164691791, 5880.558958078119, 3892.8442862285756, 3906.7851268179957, 3916.8824935251987, 3937.448757173998, 3940.0, 3799.2591444668024, 3942.250972315251, 3964.5440774311323, 3984.7409918355793, 3989.2819292527624, 4562.680612357103, 4567.657425693086, 4574.382458368144, 4582.381378513925, 4583.587477127075, 5495.53287970519, 5507.118860982224, 5522.779088836482, 5531.668099914018, 5532.059549876531, 1259.4206664934234, 1291.4834066373526, 1469.6252455623749, 1519.369461673393, 1530.2731202678638, 1067.3702515515727, 1092.8415379770047, 1096.8245080787035, 1098.5056724474018, 1105.657246253664, 4765.737884416321, 4774.809007639656, 4780.211265141283, 4798.568128868418, 4803.351547541107, 5678.602497825113, 5693.430656934273, 5693.800716974886, 5695.629784064023, 5730.36965933747, 4703.788329525111, 4764.2615394613895, 4766.95233047672, 4798.304245884327, 4807.18277892759, 2987.4473222453917, 3002.8348440835525, 3011.9548206776917, 3013.2316259353724, 3013.3069394039494, 5262.447443018505, 5270.736463176826, 5279.86482906177, 5291.576673866081, 5302.708351040653, 5639.984802735547, 5800.216970708984, 5819.505342045889, 5838.299404071538, 5842.853928651818, 5255.894882102337, 5261.579073674096, 5273.235380869753, 5274.736263186748, 5287.0, 5528.364238112668, 5675.353023741403, 5684.232628595252, 5705.315362156568, 5713.428657134254, 5591.720413979366, 5605.859853503532, 5606.355269144101, 5610.90587335482, 5623.8189980104635, 5280.894382112413, 5292.9206061908335, 5297.443768404262, 5299.549538289134, 5306.28365170697, 4215.533912661128, 4274.380214868765, 4311.719738217006, 4313.266744653353, 4315.346062171679, 5433.212184233343, 5585.0, 5655.123455864347, 5666.376698563073, 5702.344230413555, 5250.65870718402, 5476.780928762964, 5484.095124304445, 5493.176023596333, 5506.779728810803, 5490.6431081979545, 5491.066518691752, 5493.285872836505, 5497.615166938276, 5506.256655351476, 5307.9734601326745, 5317.57459403239, 5402.918956215739, 5453.509184173444, 5465.644733092496, 5504.834854954358, 5526.806561770319, 5535.308086489189, 5545.72271386421, 5561.4716601922755, 5257.5005374489365, 5260.9736951315, 5326.36083669962, 5364.14173732201, 5367.919481207863], "value": 4179.049359727025, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.5377888819488, 750.4634186556615, 750.6847124207832, 761.6153842309633, 772.8222508822969, 12386.81419778703, 12955.546555870547, 13013.544525941583, 13063.934680326585, 13071.281079540646, 13107.34463276837, 13346.132501387427, 13371.59885203443, 13383.99620028497, 13858.307084645752, 892.2594246775177, 911.1253196930954, 913.5477938420491, 917.8485549884294, 918.2837386838277, 913.3880300198882, 918.9494577798218, 920.2821798996794, 920.9907900920972, 926.819270242303, 966.1980556138388, 966.5264020629876, 967.8790151231096, 968.1915600473613, 969.0261287406147, 954.4511905654276, 955.3408148377666, 961.0917682789767, 961.6297725375711, 962.3792653738335, 689.4691087862318, 690.6788343420284, 693.3066933066933, 693.6011793218869, 693.8369483171501, 15079.54761357161, 15242.542723718307, 15434.691306173925, 15441.459548915735, 15472.303746331441, 14447.205403702792, 14519.564413067625, 14656.560303190921, 14665.48670796517, 14675.97268191227, 11619.767604647945, 11751.294922304689, 11928.58249961256, 11971.521139154424, 11991.400429978466, 3063.2801291696433, 3067.5245336972803, 3095.9535606965915, 3101.0, 3102.519109538025, 61357.38642613606, 62770.68614656943, 70350.0, 71321.0, 72532.0, 12348.814767778491, 12667.746645067231, 12900.35498225085, 12952.611421657366, 12982.805257921138, 21996.120155193934, 22261.443463913532, 22711.0, 22734.317970460914, 22770.31689049331, 6163.1679723237685, 6227.782027629011, 6246.281677607042, 6246.312905580383, 6247.094171345129, 3153.1486498645236, 3200.087974927181, 3204.983975080156, 3212.855421506038, 3215.871365145415, 3608.079939615434, 4055.9594404055583, 4138.420621113047, 4153.854615088458, 4154.231467178582, 773.1456740301998, 786.9331106855981, 801.4870482890955, 802.0335495727634, 803.3613277444446, 591.7189335065809, 602.3284038297267, 611.6207951070364, 620.1999420747276, 626.2453743239386, 9699.95602286807, 10149.98500149979, 10388.844167337496, 10859.18556108295, 10999.23005389615, 600.3353939887053, 611.339753066687, 612.5252928980076, 620.1101419463085, 625.6402568523117, 4613.63090952723, 4783.162946484351, 4785.377900872864, 4881.536254055859, 4955.752212389366, 61573.384266157664, 61829.38170618326, 62182.68908655518, 62230.68884655548, 63607.68196158989, 54693.0, 56959.71520142373, 57065.42934570684, 57205.606872941054, 57759.4224057754, 35066.824665877015, 36690.63309366872, 36826.63173368232, 45971.540284597395, 51014.74492627513, 9670.274729395183, 10308.381497110197, 10411.0, 10605.628802991858, 10718.571257149626, 24778.380540485912, 25154.874225628755, 25408.745912541377, 25667.871660641576, 26405.0, 30896.0, 30906.690933091282, 31191.68808311888, 31225.687743123188, 31905.20236994001, 13393.0, 13548.59354219375, 13647.727045458836, 13764.105333153313, 13848.30758462093, 14852.257387130816, 15277.236138193268, 15453.845461545241, 15538.611534711723, 15545.145017024171, 13921.025528212927, 14479.638009049859, 14864.554063378117, 15132.773008404663, 15141.470048548248, 13807.723845523235, 14131.64670883236, 14589.781153282922, 14596.708065838837, 14879.92560037193, 11993.520259189534, 12187.878121218675, 12412.503499860266, 13538.255395953318, 13854.722905541632, 9773.804523909625, 9787.314887957775, 10010.8498372523, 10132.898671013196, 10551.94724026375, 10093.243006774668, 10724.410157441103, 11155.442227888736, 11259.49332280033, 11477.426128693365], "value": 6813.402061119764, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 113.0, 112.0, 114.0, 110.0, 112.0, 111.0, 112.0, 114.0, 111.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 122.0, 177.0, 125.0, 121.0, 122.0, 121.0, 146.0, 121.0], "value": 133.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 163.0, 163.0, 161.0, 162.0, 163.0, 159.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 78.0, 105.0, 75.0, 76.0, 75.0, 76.0, 96.0, 74.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 112.0, 84.0, 83.0, 83.0, 97.0, 83.0, 84.0, 105.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 148.0, 148.0, 149.0, 150.0, 148.0, 148.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 54.0, 56.0, 55.0, 55.0, 56.0, 55.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0, 55.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 83.0, 82.0, 81.0, 81.0, 82.0, 83.0, 81.0, 82.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 131.0, 139.0, 131.0, 131.0, 130.0, 137.0, 133.0, 183.0, 132.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 195.0, 192.0, 183.0, 188.0, 192.0, 192.0, 190.0, 188.0, 194.0], "value": 190.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 71.0, 75.0, 72.0, 72.0, 75.0, 71.0, 71.0, 70.0], "value": 72.5, "unit": "score"}]}]} 18:44:16 INFO - Return code: 0 18:44:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:16 INFO - # TBPL SUCCESS # 18:44:16 INFO - Running post-action listener: _resource_record_post_action 18:44:16 INFO - [mozharness: 2017-05-12 01:44:16.327280Z] Finished run-tests step (success) 18:44:16 INFO - Running post-run listener: _resource_record_post_run 18:44:16 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 7974912; Write bytes: 369098752; Read time: 2416; Write time: 302844 18:44:16 INFO - TinderboxPrint: CPU usage
12.9% 18:44:16 INFO - TinderboxPrint: I/O read bytes / time
7,974,912 / 2,416 18:44:16 INFO - TinderboxPrint: I/O write bytes / time
369,098,752 / 302,844 18:44:16 INFO - TinderboxPrint: CPU idle
8,353.0 (87.1%) 18:44:16 INFO - TinderboxPrint: CPU user
1,197.3 (12.5%) 18:44:16 INFO - TinderboxPrint: Swap in / out
0 / 0 18:44:16 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 111271936; Read time: 260; Write time: 105788 18:44:16 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 5623808; Write bytes: 253964288; Read time: 1616; Write time: 196020 18:44:16 INFO - Running post-run listener: _upload_blobber_files 18:44:16 INFO - Blob upload gear active. 18:44:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:16 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 18:44:16 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:44:16 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:44:17 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:44:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:44:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:18 INFO - (blobuploader) - INFO - Done attempting. 18:44:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:44:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:44:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:18 INFO - (blobuploader) - INFO - Done attempting. 18:44:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:44:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:44:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:20 INFO - (blobuploader) - INFO - Done attempting. 18:44:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:24 INFO - (blobuploader) - INFO - Done attempting. 18:44:24 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:24 INFO - Return code: 0 18:44:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0903eeb888110f2c993c397fdca154384900acc3099bd6ff111e0ed323afb39c2720d57ae47640b802ee0482bb5a4a583bb19b710fde14c6c8b4ae3b509fa04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c98e0a058e68af750608b0fff58e8b9f715e959196feaa2e151e41029c6ce28644d9c566123ab4fbcd4164486878fe98ed7d0a359e62adae15b9085a05a220b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/74d50074c21754579c05e51adfaf30a5c8187ca61b30ddb88861c01c3756abec95f86adf6e210940b8453b8cab5924ca2cf239162c84aa2666fc89f9b24b2a70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31ddabfa92211aa5c623d5b8fb813e90631221ac139f7cfcc7da3417cd634970abde457990ed6a07d879ec766dfdcdc5b8b795de127546ebaac36364db4008d6"} 18:44:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:24 INFO - Contents: 18:44:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0903eeb888110f2c993c397fdca154384900acc3099bd6ff111e0ed323afb39c2720d57ae47640b802ee0482bb5a4a583bb19b710fde14c6c8b4ae3b509fa04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c98e0a058e68af750608b0fff58e8b9f715e959196feaa2e151e41029c6ce28644d9c566123ab4fbcd4164486878fe98ed7d0a359e62adae15b9085a05a220b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/74d50074c21754579c05e51adfaf30a5c8187ca61b30ddb88861c01c3756abec95f86adf6e210940b8453b8cab5924ca2cf239162c84aa2666fc89f9b24b2a70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31ddabfa92211aa5c623d5b8fb813e90631221ac139f7cfcc7da3417cd634970abde457990ed6a07d879ec766dfdcdc5b8b795de127546ebaac36364db4008d6"} 18:44:24 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:24 INFO - Copying logs to upload dir... 18:44:24 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.270497 ========= master_lag: 0.28 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-05-11 18:44:24.716496) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 18:44:24.720734) ========= 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=17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444 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-Non-PGO/sha512/e0903eeb888110f2c993c397fdca154384900acc3099bd6ff111e0ed323afb39c2720d57ae47640b802ee0482bb5a4a583bb19b710fde14c6c8b4ae3b509fa04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c98e0a058e68af750608b0fff58e8b9f715e959196feaa2e151e41029c6ce28644d9c566123ab4fbcd4164486878fe98ed7d0a359e62adae15b9085a05a220b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/74d50074c21754579c05e51adfaf30a5c8187ca61b30ddb88861c01c3756abec95f86adf6e210940b8453b8cab5924ca2cf239162c84aa2666fc89f9b24b2a70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31ddabfa92211aa5c623d5b8fb813e90631221ac139f7cfcc7da3417cd634970abde457990ed6a07d879ec766dfdcdc5b8b795de127546ebaac36364db4008d6"} build_url:https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011269 build_url: 'https://queue.taskcluster.net/v1/task/dGUIyOthRJK_MESGw0MYbQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0903eeb888110f2c993c397fdca154384900acc3099bd6ff111e0ed323afb39c2720d57ae47640b802ee0482bb5a4a583bb19b710fde14c6c8b4ae3b509fa04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c98e0a058e68af750608b0fff58e8b9f715e959196feaa2e151e41029c6ce28644d9c566123ab4fbcd4164486878fe98ed7d0a359e62adae15b9085a05a220b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/74d50074c21754579c05e51adfaf30a5c8187ca61b30ddb88861c01c3756abec95f86adf6e210940b8453b8cab5924ca2cf239162c84aa2666fc89f9b24b2a70", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31ddabfa92211aa5c623d5b8fb813e90631221ac139f7cfcc7da3417cd634970abde457990ed6a07d879ec766dfdcdc5b8b795de127546ebaac36364db4008d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 18:44:24.748717) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:44:24.749047) ========= 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=17051fcd5208b27b50bedfeb000001dd-1494552200.63833-298312444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004365 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 18:44:24.829223) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 18:44:24.832550) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 18:44:24.832953) ========= ========= Total master_lag: 0.44 =========