builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-004 starttime: 1494547948.47 results: success (0) revision: c1719e0d676b56ccd940c6f8a9b1bb819d441b20 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:28.474252) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:28.474689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:28.475101) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:28.491068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:28.491703) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-11 17:12:28-- 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.0M=0s 2017-05-11 17:12:29 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.349185 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:28.856262) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:28.856606) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018200 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:28.906301) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 17:12:28.909691) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c1719e0d676b56ccd940c6f8a9b1bb819d441b20 --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 c1719e0d676b56ccd940c6f8a9b1bb819d441b20 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-11 17:12:29,402 truncating revision to first 12 chars 2017-05-11 17:12:29,402 Setting DEBUG logging. 2017-05-11 17:12:29,402 attempt 1/10 2017-05-11 17:12:29,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c1719e0d676b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 17:12:30,160 unpacking tar archive at: mozilla-central-c1719e0d676b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.162685 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 17:12:30.088710) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:30.089033) ========= script_repo_revision: c1719e0d676b56ccd940c6f8a9b1bb819d441b20 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:30.089392) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:30.089664) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 17:12:30.099739) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-05-11 17:12:30.100080) ========= /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=c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:12:30 INFO - MultiFileLogger online at 20170511 17:12:30 in /builds/slave/test 17:12:30 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 17:12:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:12:30 INFO - {'append_to_log': False, 17:12:30 INFO - 'base_work_dir': '/builds/slave/test', 17:12:30 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:12:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:12:30 INFO - 'branch': 'Firefox-Non-PGO', 17:12:30 INFO - 'buildbot_json_path': 'buildprops.json', 17:12:30 INFO - 'config_files': ('talos/linux_config.py',), 17:12:30 INFO - 'default_actions': ('clobber', 17:12:30 INFO - 'read-buildbot-config', 17:12:30 INFO - 'download-and-extract', 17:12:30 INFO - 'populate-webroot', 17:12:30 INFO - 'create-virtualenv', 17:12:30 INFO - 'install', 17:12:30 INFO - 'run-tests'), 17:12:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:12:30 INFO - 'download_minidump_stackwalk': True, 17:12:30 INFO - 'download_symbols': 'ondemand', 17:12:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:12:30 INFO - 'tooltool.py': '/tools/tooltool.py', 17:12:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:12:30 INFO - '/tools/misc-python/virtualenv.py')}, 17:12:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:12:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:12:30 INFO - 'gecko_profile': False, 17:12:30 INFO - 'gecko_profile_interval': 0, 17:12:30 INFO - 'installer_path': 'installer.exe', 17:12:30 INFO - 'log_level': 'info', 17:12:30 INFO - 'log_name': 'talos', 17:12:30 INFO - 'log_to_console': True, 17:12:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:12:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:12:30 INFO - 'opt_config_files': (), 17:12:30 INFO - 'pip_index': False, 17:12:30 INFO - 'suite': 'dromaeojs', 17:12:30 INFO - 'system_bits': '32', 17:12:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:12:30 INFO - 'title': 'talos-linux64-ix-004', 17:12:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:12:30 INFO - 'use_talos_json': True, 17:12:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:12:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:12:30 INFO - 'work_dir': 'build'} 17:12:30 INFO - [mozharness: 2017-05-12 00:12:30.690155Z] Running clobber step. 17:12:30 INFO - Running pre-action listener: _resource_record_pre_action 17:12:30 INFO - Running main action method: clobber 17:12:30 INFO - rmtree: /builds/slave/test/build 17:12:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:12:31 INFO - Running post-action listener: _resource_record_post_action 17:12:31 INFO - [mozharness: 2017-05-12 00:12:31.639537Z] Finished clobber step (success) 17:12:31 INFO - [mozharness: 2017-05-12 00:12:31.639638Z] Running read-buildbot-config step. 17:12:31 INFO - Running pre-action listener: _resource_record_pre_action 17:12:31 INFO - Running main action method: read_buildbot_config 17:12:31 INFO - Using buildbot properties: 17:12:31 INFO - { 17:12:31 INFO - "project": "", 17:12:31 INFO - "product": "firefox", 17:12:31 INFO - "who": "kwierso@gmail.com", 17:12:31 INFO - "installer_path": "public/build/target.tar.bz2", 17:12:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 17:12:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:12:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 17:12:31 INFO - "stage_platform": "linux64", 17:12:31 INFO - "basedir": "/builds/slave/test", 17:12:31 INFO - "buildnumber": 988, 17:12:31 INFO - "platform": "ubuntu64_hw", 17:12:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:12:31 INFO - "slavebuilddir": "test", 17:12:31 INFO - "taskId": "AwD8Y2ZVSVy8P1COpNMM7Q", 17:12:31 INFO - "branch": "mozilla-central", 17:12:31 INFO - "script_repo_revision": "production", 17:12:31 INFO - "revision": "c1719e0d676b56ccd940c6f8a9b1bb819d441b20", 17:12:31 INFO - "slavename": "talos-linux64-ix-004", 17:12:31 INFO - "repo_path": "mozilla-central" 17:12:31 INFO - } 17:12:31 INFO - Finding installer, test and symbols from parent task. 17:12:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AwD8Y2ZVSVy8P1COpNMM7Q'}, attempt #1 17:12:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AwD8Y2ZVSVy8P1COpNMM7Q'}, attempt #1 17:12:32 INFO - Task dependencies: ZUPAr4LpSymjIB81NaP2cA 17:12:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA'}, attempt #1 17:12:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.tar.bz2 17:12:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/test_packages.json 17:12:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.crashreporter-symbols.zip 17:12:33 INFO - Running post-action listener: _resource_record_post_action 17:12:33 INFO - [mozharness: 2017-05-12 00:12:33.005209Z] Finished read-buildbot-config step (success) 17:12:33 INFO - [mozharness: 2017-05-12 00:12:33.005470Z] Running download-and-extract step. 17:12:33 INFO - Running pre-action listener: _resource_record_pre_action 17:12:33 INFO - Running main action method: download_and_extract 17:12:33 INFO - mkdir: /builds/slave/test/build/tests 17:12:33 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')]} 17:12:33 INFO - trying https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.test_packages.json 17:12:33 INFO - Downloading https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:12:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:12:33 INFO - Downloaded 986 bytes. 17:12:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:12:33 INFO - Using the following test package requirements: 17:12:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:12:33 INFO - u'common': [u'target.common.tests.zip'], 17:12:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:12:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:12:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:12:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:12:33 INFO - u'mozbase': [u'target.common.tests.zip'], 17:12:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:12:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:12:33 INFO - u'web-platform': [u'target.common.tests.zip', 17:12:33 INFO - u'target.web-platform.tests.zip'], 17:12:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:12:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:12:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.common.tests.zip 17:12:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:12:33 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.common.tests.zip into memory 17:12:34 INFO - Content-Length response header: 58588449 17:12:34 INFO - Bytes received: 58588449 17:12:44 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:12:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.common.tests.zip 17:12:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:12:44 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.common.tests.zip into memory 17:12:45 INFO - Content-Length response header: 58588449 17:12:45 INFO - Bytes received: 58588449 17:12:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.talos.tests.zip 17:12:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:12:56 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.talos.tests.zip into memory 17:12:57 INFO - Content-Length response header: 14409411 17:12:57 INFO - Bytes received: 14409411 17:12: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')]} 17:12:57 INFO - trying https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.tar.bz2 17:12:57 INFO - Downloading https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:12:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:12:58 INFO - Downloaded 62973840 bytes. 17:12:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.tar.bz2 17:12:58 INFO - mkdir: /builds/slave/test/properties 17:12:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:12:58 INFO - Writing to file /builds/slave/test/properties/build_url 17:12:58 INFO - Contents: 17:12:58 INFO - build_url:https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.tar.bz2 17:12:58 INFO - Running post-action listener: _resource_record_post_action 17:12:58 INFO - Running post-action listener: set_extra_try_arguments 17:12:58 INFO - [mozharness: 2017-05-12 00:12:58.698204Z] Finished download-and-extract step (success) 17:12:58 INFO - [mozharness: 2017-05-12 00:12:58.698302Z] Running populate-webroot step. 17:12:58 INFO - Running pre-action listener: _resource_record_pre_action 17:12:58 INFO - Running main action method: populate_webroot 17:12:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:12:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:12:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:12:58 INFO - '--tppagecycles', 17:12:58 INFO - '7'], 17:12:58 INFO - 'tests': ['tsvgm']}}, 17:12:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:12:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:12:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:12:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:12:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:12:58 INFO - 'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:12:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:12:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:12:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:12:58 INFO - 'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['damp', 'tps']}, 17:12:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:12:58 INFO - 'tests': ['damp', 'tps']}, 17:12:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['dromaeo_dom']}, 17:12:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:12:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:12:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:12:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['a11yr', 17:12:58 INFO - 'ts_paint', 17:12:58 INFO - 'tpaint', 17:12:58 INFO - 'sessionrestore', 17:12:58 INFO - 'sessionrestore_no_auto_restore', 17:12:58 INFO - 'tabpaint']}, 17:12:58 INFO - 'other-e10s': {'tests': ['a11yr', 17:12:58 INFO - 'ts_paint', 17:12:58 INFO - 'tpaint', 17:12:58 INFO - 'sessionrestore', 17:12:58 INFO - 'sessionrestore_no_auto_restore', 17:12:58 INFO - 'tabpaint']}, 17:12:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:12:58 INFO - 'ts_paint', 17:12:58 INFO - 'tpaint', 17:12:58 INFO - 'sessionrestore', 17:12:58 INFO - 'sessionrestore_no_auto_restore', 17:12:58 INFO - 'tabpaint']}, 17:12:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:12:58 INFO - 'ts_paint', 17:12:58 INFO - 'tpaint', 17:12:58 INFO - 'sessionrestore', 17:12:58 INFO - 'sessionrestore_no_auto_restore', 17:12:58 INFO - 'tabpaint']}, 17:12:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['a11yr', 17:12:58 INFO - 'ts_paint', 17:12:58 INFO - 'tpaint', 17:12:58 INFO - 'sessionrestore', 17:12:58 INFO - 'sessionrestore_no_auto_restore', 17:12:58 INFO - 'tabpaint']}, 17:12:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['a11yr', 17:12:58 INFO - 'ts_paint', 17:12:58 INFO - 'tpaint', 17:12:58 INFO - 'sessionrestore', 17:12:58 INFO - 'sessionrestore_no_auto_restore', 17:12:58 INFO - 'tabpaint']}, 17:12:58 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:12:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:12:58 INFO - 'bloom_basic_ref']}, 17:12:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['tsvgx', 17:12:58 INFO - 'tsvgr_opacity', 17:12:58 INFO - 'tart', 17:12:58 INFO - 'tscrollx', 17:12:58 INFO - 'cart', 17:12:58 INFO - 'tsvg_static']}, 17:12:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:12:58 INFO - 'tsvgr_opacity', 17:12:58 INFO - 'tart', 17:12:58 INFO - 'tscrollx', 17:12:58 INFO - 'cart', 17:12:58 INFO - 'tsvg_static']}, 17:12:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:12:58 INFO - 'talos_options': ['--disable-e10s'], 17:12:58 INFO - 'tests': ['tp5o']}, 17:12:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:12:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:12:58 INFO - 'talos_options': ['--disable-e10s', 17:12:58 INFO - '--xperf_path', 17:12:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:12:58 INFO - 'tests': ['tp5n']}, 17:12:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:12:58 INFO - 'talos_options': ['--xperf_path', 17:12:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:12:58 INFO - 'tests': ['tp5n']}}, 17:12:58 INFO - 'talos.zip': {'path': '', 17:12:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:12:58 INFO - Running post-action listener: _resource_record_post_action 17:12:58 INFO - [mozharness: 2017-05-12 00:12:58.711992Z] Finished populate-webroot step (success) 17:12:58 INFO - [mozharness: 2017-05-12 00:12:58.712081Z] Running create-virtualenv step. 17:12:58 INFO - Running pre-action listener: _resource_record_pre_action 17:12:58 INFO - Running main action method: create_virtualenv 17:12:58 INFO - Creating virtualenv /builds/slave/test/build/venv 17:12:58 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 17:12:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:12:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:12:58 INFO - Using real prefix '/usr' 17:12:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:12:59 INFO - Installing distribute.............................................................................................................................................................................................done. 17:13:01 INFO - Installing pip.................done. 17:13:01 INFO - Return code: 0 17:13:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:13:01 INFO - Reading from file tmpfile_stdout 17:13:01 INFO - Output received: 17:13:01 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) 17:13:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13: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')]} 17:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13: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', 'pip>=1.5'] in /builds/slave/test/build 17:13: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 pip>=1.5 17:13:01 INFO - Using env: {'DISPLAY': ':0', 17:13:01 INFO - 'HOME': '/home/cltbld', 17:13:01 INFO - 'LANG': 'en_US.UTF-8', 17:13:01 INFO - 'LANGUAGE': 'en_US:en', 17:13:01 INFO - 'LOGNAME': 'cltbld', 17:13:01 INFO - 'MAIL': '/var/mail/cltbld', 17:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:01 INFO - 'MOZ_NO_REMOTE': '1', 17:13:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:01 INFO - 'NO_EM_RESTART': '1', 17:13:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:01 INFO - 'PWD': '/builds/slave/test', 17:13:01 INFO - 'SHELL': '/bin/bash', 17:13:01 INFO - 'SHLVL': '1', 17:13:01 INFO - 'TERM': 'linux', 17:13:01 INFO - 'TMOUT': '86400', 17:13:01 INFO - 'USER': 'cltbld', 17:13:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:01 INFO - '_': '/tools/buildbot/bin/python'} 17:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:01 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 17:13:01 INFO - Cleaning up... 17:13:01 INFO - Return code: 0 17:13:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13: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')]} 17:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13: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', '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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:13: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 psutil>=3.1.1 17:13:01 INFO - Using env: {'DISPLAY': ':0', 17:13:01 INFO - 'HOME': '/home/cltbld', 17:13:01 INFO - 'LANG': 'en_US.UTF-8', 17:13:01 INFO - 'LANGUAGE': 'en_US:en', 17:13:01 INFO - 'LOGNAME': 'cltbld', 17:13:01 INFO - 'MAIL': '/var/mail/cltbld', 17:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:01 INFO - 'MOZ_NO_REMOTE': '1', 17:13:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:01 INFO - 'NO_EM_RESTART': '1', 17:13:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:01 INFO - 'PWD': '/builds/slave/test', 17:13:01 INFO - 'SHELL': '/bin/bash', 17:13:01 INFO - 'SHLVL': '1', 17:13:01 INFO - 'TERM': 'linux', 17:13:01 INFO - 'TMOUT': '86400', 17:13:01 INFO - 'USER': 'cltbld', 17:13:01 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:01 INFO - '_': '/tools/buildbot/bin/python'} 17:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:01 INFO - Downloading/unpacking psutil>=3.1.1 17:13: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 17:13: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 17:13: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 17:13: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 17:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:13:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:13:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:13:03 INFO - Installing collected packages: psutil 17:13:03 INFO - Running setup.py install for psutil 17:13:03 INFO - building 'psutil._psutil_linux' extension 17:13:03 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 17:13:03 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 17:13:03 INFO - building 'psutil._psutil_posix' extension 17:13:03 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 17:13:03 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 17:13:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:13:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:13:03 INFO - Successfully installed psutil 17:13:03 INFO - Cleaning up... 17:13:03 INFO - Return code: 0 17:13:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:13:03 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')]} 17:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:03 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')]} 17:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:03 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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13:03 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 17:13:03 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 17:13:03 INFO - Using env: {'DISPLAY': ':0', 17:13:03 INFO - 'HOME': '/home/cltbld', 17:13:03 INFO - 'LANG': 'en_US.UTF-8', 17:13:03 INFO - 'LANGUAGE': 'en_US:en', 17:13:03 INFO - 'LOGNAME': 'cltbld', 17:13:03 INFO - 'MAIL': '/var/mail/cltbld', 17:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:03 INFO - 'MOZ_NO_REMOTE': '1', 17:13:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:03 INFO - 'NO_EM_RESTART': '1', 17:13:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:03 INFO - 'PWD': '/builds/slave/test', 17:13:03 INFO - 'SHELL': '/bin/bash', 17:13:03 INFO - 'SHLVL': '1', 17:13:03 INFO - 'TERM': 'linux', 17:13:03 INFO - 'TMOUT': '86400', 17:13:03 INFO - 'USER': 'cltbld', 17:13:03 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:03 INFO - '_': '/tools/buildbot/bin/python'} 17:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:13: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 17:13: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 17:13: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 17:13: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 17:13:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:13:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:13:05 INFO - Installing collected packages: mozsystemmonitor 17:13:05 INFO - Running setup.py install for mozsystemmonitor 17:13:05 INFO - Successfully installed mozsystemmonitor 17:13:05 INFO - Cleaning up... 17:13:05 INFO - Return code: 0 17:13:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:13:05 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')]} 17:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:05 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')]} 17:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13:05 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 17:13:05 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 17:13:05 INFO - Using env: {'DISPLAY': ':0', 17:13:05 INFO - 'HOME': '/home/cltbld', 17:13:05 INFO - 'LANG': 'en_US.UTF-8', 17:13:05 INFO - 'LANGUAGE': 'en_US:en', 17:13:05 INFO - 'LOGNAME': 'cltbld', 17:13:05 INFO - 'MAIL': '/var/mail/cltbld', 17:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:05 INFO - 'MOZ_NO_REMOTE': '1', 17:13:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:05 INFO - 'NO_EM_RESTART': '1', 17:13:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:05 INFO - 'PWD': '/builds/slave/test', 17:13:05 INFO - 'SHELL': '/bin/bash', 17:13:05 INFO - 'SHLVL': '1', 17:13:05 INFO - 'TERM': 'linux', 17:13:05 INFO - 'TMOUT': '86400', 17:13:05 INFO - 'USER': 'cltbld', 17:13:05 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:05 INFO - '_': '/tools/buildbot/bin/python'} 17:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:05 INFO - Downloading/unpacking jsonschema==2.5.1 17:13: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 17:13: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 17:13: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 17:13: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 17:13:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:13:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:13: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 17:13: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 17:13: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 17:13: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 17:13:06 INFO - Downloading functools32-3.2.3-2.tar.gz 17:13:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:13:06 INFO - warning: no files found matching '*.txt' 17:13:06 INFO - no previously-included directories found matching 'build' 17:13:06 INFO - no previously-included directories found matching 'dist' 17:13:06 INFO - no previously-included directories found matching '.git*' 17:13:06 INFO - Installing collected packages: jsonschema, functools32 17:13:06 INFO - Running setup.py install for functools32 17:13:06 INFO - warning: no files found matching '*.txt' 17:13:06 INFO - no previously-included directories found matching 'build' 17:13:06 INFO - no previously-included directories found matching 'dist' 17:13:06 INFO - no previously-included directories found matching '.git*' 17:13:06 INFO - Successfully installed jsonschema functools32 17:13:06 INFO - Cleaning up... 17:13:06 INFO - Return code: 0 17:13:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:13:06 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')]} 17:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:06 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')]} 17:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:06 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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13:06 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 17:13:06 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 17:13:06 INFO - Using env: {'DISPLAY': ':0', 17:13:06 INFO - 'HOME': '/home/cltbld', 17:13:06 INFO - 'LANG': 'en_US.UTF-8', 17:13:06 INFO - 'LANGUAGE': 'en_US:en', 17:13:06 INFO - 'LOGNAME': 'cltbld', 17:13:06 INFO - 'MAIL': '/var/mail/cltbld', 17:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:06 INFO - 'MOZ_NO_REMOTE': '1', 17:13:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:06 INFO - 'NO_EM_RESTART': '1', 17:13:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:06 INFO - 'PWD': '/builds/slave/test', 17:13:06 INFO - 'SHELL': '/bin/bash', 17:13:06 INFO - 'SHLVL': '1', 17:13:06 INFO - 'TERM': 'linux', 17:13:06 INFO - 'TMOUT': '86400', 17:13:06 INFO - 'USER': 'cltbld', 17:13:06 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:06 INFO - '_': '/tools/buildbot/bin/python'} 17:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:13:07 INFO - Cleaning up... 17:13:07 INFO - Return code: 0 17:13:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13: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')]} 17:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:07 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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13:07 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 17:13:07 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 17:13:07 INFO - Using env: {'DISPLAY': ':0', 17:13:07 INFO - 'HOME': '/home/cltbld', 17:13:07 INFO - 'LANG': 'en_US.UTF-8', 17:13:07 INFO - 'LANGUAGE': 'en_US:en', 17:13:07 INFO - 'LOGNAME': 'cltbld', 17:13:07 INFO - 'MAIL': '/var/mail/cltbld', 17:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:07 INFO - 'MOZ_NO_REMOTE': '1', 17:13:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:07 INFO - 'NO_EM_RESTART': '1', 17:13:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:07 INFO - 'PWD': '/builds/slave/test', 17:13:07 INFO - 'SHELL': '/bin/bash', 17:13:07 INFO - 'SHLVL': '1', 17:13:07 INFO - 'TERM': 'linux', 17:13:07 INFO - 'TMOUT': '86400', 17:13:07 INFO - 'USER': 'cltbld', 17:13:07 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:07 INFO - '_': '/tools/buildbot/bin/python'} 17:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:07 INFO - Downloading/unpacking blobuploader==1.2.4 17:13:07 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 17:13:07 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 17:13:07 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 17:13:07 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 17:13:08 INFO - Downloading blobuploader-1.2.4.tar.gz 17:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:13:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:13:08 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 17:13:08 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 17:13:08 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 17:13:08 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 17:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:13:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:13:08 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 17:13:08 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 17:13:08 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 17:13:08 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 17:13:09 INFO - Downloading docopt-0.6.1.tar.gz 17:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:13:09 INFO - Installing collected packages: blobuploader, requests, docopt 17:13:09 INFO - Running setup.py install for blobuploader 17:13:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:13:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:13:09 INFO - Running setup.py install for requests 17:13:09 INFO - Running setup.py install for docopt 17:13:09 INFO - Successfully installed blobuploader requests docopt 17:13:09 INFO - Cleaning up... 17:13:09 INFO - Return code: 0 17:13:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13:09 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')]} 17:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:09 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')]} 17:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:09 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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13:09 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 17:13:09 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 17:13:09 INFO - Using env: {'DISPLAY': ':0', 17:13:09 INFO - 'HOME': '/home/cltbld', 17:13:09 INFO - 'LANG': 'en_US.UTF-8', 17:13:09 INFO - 'LANGUAGE': 'en_US:en', 17:13:09 INFO - 'LOGNAME': 'cltbld', 17:13:09 INFO - 'MAIL': '/var/mail/cltbld', 17:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:09 INFO - 'MOZ_NO_REMOTE': '1', 17:13:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:09 INFO - 'NO_EM_RESTART': '1', 17:13:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:09 INFO - 'PWD': '/builds/slave/test', 17:13:09 INFO - 'SHELL': '/bin/bash', 17:13:09 INFO - 'SHLVL': '1', 17:13:09 INFO - 'TERM': 'linux', 17:13:09 INFO - 'TMOUT': '86400', 17:13:09 INFO - 'USER': 'cltbld', 17:13:09 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:09 INFO - '_': '/tools/buildbot/bin/python'} 17:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:13:09 INFO - Running setup.py (path:/tmp/pip-LgKry_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:13:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:13:09 INFO - Running setup.py (path:/tmp/pip-p6gO1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:13:10 INFO - Running setup.py (path:/tmp/pip-osvKw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:13:10 INFO - Running setup.py (path:/tmp/pip-1NDqhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:13:10 INFO - Running setup.py (path:/tmp/pip-srYuSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:13:10 INFO - Running setup.py (path:/tmp/pip-uYbbtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:13:10 INFO - Running setup.py (path:/tmp/pip-Zi6H0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:13:10 INFO - Running setup.py (path:/tmp/pip-cblxBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:13:10 INFO - Running setup.py (path:/tmp/pip-2rVQyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:13:10 INFO - Running setup.py (path:/tmp/pip-LWFyB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:13:10 INFO - Running setup.py (path:/tmp/pip-dk3xQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:13:10 INFO - Running setup.py (path:/tmp/pip-QOgfRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:13:10 INFO - Running setup.py (path:/tmp/pip-RZE18z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:13:10 INFO - Running setup.py (path:/tmp/pip-N0ATuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:13:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:10 INFO - Running setup.py (path:/tmp/pip-66Kq3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:13:11 INFO - Running setup.py (path:/tmp/pip-N48ugO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:13:11 INFO - Running setup.py (path:/tmp/pip-IFaJwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:13:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:13:11 INFO - Running setup.py install for manifestparser 17:13:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:13:11 INFO - Running setup.py install for mozcrash 17:13:11 INFO - Running setup.py install for mozdebug 17:13:11 INFO - Running setup.py install for mozdevice 17:13:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:13:11 INFO - Running setup.py install for mozfile 17:13:11 INFO - Running setup.py install for mozhttpd 17:13:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:13:11 INFO - Running setup.py install for mozinfo 17:13:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:13:11 INFO - Running setup.py install for mozInstall 17:13:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:13:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:13:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:13:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:13:11 INFO - Running setup.py install for mozleak 17:13:12 INFO - Running setup.py install for mozlog 17:13:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:13:12 INFO - Running setup.py install for moznetwork 17:13:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:13:12 INFO - Running setup.py install for mozprocess 17:13:12 INFO - Running setup.py install for mozprofile 17:13:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:13:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:13:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:13:12 INFO - Running setup.py install for mozrunner 17:13:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:13:12 INFO - Running setup.py install for mozscreenshot 17:13:12 INFO - Running setup.py install for moztest 17:13:12 INFO - Running setup.py install for mozversion 17:13:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:13:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:13:12 INFO - Cleaning up... 17:13:12 INFO - Return code: 0 17:13:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13:12 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')]} 17:13:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:12 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')]} 17:13:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:12 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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13:12 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 17:13:12 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 17:13:12 INFO - Using env: {'DISPLAY': ':0', 17:13:12 INFO - 'HOME': '/home/cltbld', 17:13:12 INFO - 'LANG': 'en_US.UTF-8', 17:13:12 INFO - 'LANGUAGE': 'en_US:en', 17:13:12 INFO - 'LOGNAME': 'cltbld', 17:13:12 INFO - 'MAIL': '/var/mail/cltbld', 17:13:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:12 INFO - 'MOZ_NO_REMOTE': '1', 17:13:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:12 INFO - 'NO_EM_RESTART': '1', 17:13:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:12 INFO - 'PWD': '/builds/slave/test', 17:13:12 INFO - 'SHELL': '/bin/bash', 17:13:12 INFO - 'SHLVL': '1', 17:13:12 INFO - 'TERM': 'linux', 17:13:12 INFO - 'TMOUT': '86400', 17:13:12 INFO - 'USER': 'cltbld', 17:13:12 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:12 INFO - '_': '/tools/buildbot/bin/python'} 17:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:13:13 INFO - Running setup.py (path:/tmp/pip-2_EMGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:13:13 INFO - Running setup.py (path:/tmp/pip-iMv5Uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:13:13 INFO - Running setup.py (path:/tmp/pip-FqHMX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:13:13 INFO - Running setup.py (path:/tmp/pip-5boh5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:13:13 INFO - Running setup.py (path:/tmp/pip-IGP08v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:13:13 INFO - Running setup.py (path:/tmp/pip-VUV13m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:13:13 INFO - Running setup.py (path:/tmp/pip-mkCGnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:13:13 INFO - Running setup.py (path:/tmp/pip-sXY4hP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:13:13 INFO - Running setup.py (path:/tmp/pip-gn2igO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:13:13 INFO - Running setup.py (path:/tmp/pip-24F7Ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:13:13 INFO - Running setup.py (path:/tmp/pip-u0Z84Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:13:13 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)) 17:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:13:13 INFO - Running setup.py (path:/tmp/pip-DZ5MUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:13:14 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)) 17:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:13:14 INFO - Running setup.py (path:/tmp/pip-V8yoTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:13:14 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)) 17:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:13:14 INFO - Running setup.py (path:/tmp/pip-FXAgC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:13:14 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)) 17:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:14 INFO - Running setup.py (path:/tmp/pip-J597BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:14 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)) 17:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:13:14 INFO - Running setup.py (path:/tmp/pip-wVewat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:13:14 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)) 17:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:13:14 INFO - Running setup.py (path:/tmp/pip-rgDKZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:13:14 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)) 17:13:14 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)) 17:13:14 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)) 17:13:14 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)) 17:13:14 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)) 17:13:14 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)) 17:13:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:15 INFO - Downloading blessings-1.6.tar.gz 17:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:13:15 INFO - Installing collected packages: blessings 17:13:15 INFO - Running setup.py install for blessings 17:13:15 INFO - Successfully installed blessings 17:13:15 INFO - Cleaning up... 17:13:15 INFO - Return code: 0 17:13:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:13:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:13:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:13:15 INFO - Reading from file tmpfile_stdout 17:13:15 INFO - Current package versions: 17:13:15 INFO - argparse == 1.2.1 17:13:15 INFO - blessings == 1.6 17:13:15 INFO - blobuploader == 1.2.4 17:13:15 INFO - docopt == 0.6.1 17:13:15 INFO - functools32 == 3.2.3-2 17:13:15 INFO - jsonschema == 2.5.1 17:13:15 INFO - manifestparser == 1.1 17:13:15 INFO - mozInstall == 1.12 17:13:15 INFO - mozcrash == 1.0 17:13:15 INFO - mozdebug == 0.1 17:13:15 INFO - mozdevice == 0.50 17:13:15 INFO - mozfile == 1.2 17:13:15 INFO - mozhttpd == 0.7 17:13:15 INFO - mozinfo == 0.9 17:13:15 INFO - mozleak == 0.1 17:13:15 INFO - mozlog == 3.4 17:13:15 INFO - moznetwork == 0.27 17:13:15 INFO - mozprocess == 0.25 17:13:15 INFO - mozprofile == 0.28 17:13:15 INFO - mozrunner == 6.13 17:13:15 INFO - mozscreenshot == 0.1 17:13:15 INFO - mozsystemmonitor == 0.3 17:13:15 INFO - moztest == 0.8 17:13:15 INFO - mozversion == 1.4 17:13:15 INFO - psutil == 3.1.1 17:13:15 INFO - requests == 1.2.3 17:13:15 INFO - wsgiref == 0.1.2 17:13:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13:15 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')]} 17:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:15 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')]} 17:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:15 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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13:15 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 17:13:15 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 17:13:15 INFO - Using env: {'DISPLAY': ':0', 17:13:15 INFO - 'HOME': '/home/cltbld', 17:13:15 INFO - 'LANG': 'en_US.UTF-8', 17:13:15 INFO - 'LANGUAGE': 'en_US:en', 17:13:15 INFO - 'LOGNAME': 'cltbld', 17:13:15 INFO - 'MAIL': '/var/mail/cltbld', 17:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:15 INFO - 'MOZ_NO_REMOTE': '1', 17:13:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:15 INFO - 'NO_EM_RESTART': '1', 17:13:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:15 INFO - 'PWD': '/builds/slave/test', 17:13:15 INFO - 'SHELL': '/bin/bash', 17:13:15 INFO - 'SHLVL': '1', 17:13:15 INFO - 'TERM': 'linux', 17:13:15 INFO - 'TMOUT': '86400', 17:13:15 INFO - 'USER': 'cltbld', 17:13:15 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:15 INFO - '_': '/tools/buildbot/bin/python'} 17:13:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 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)) 17:13:16 INFO - Cleaning up... 17:13:16 INFO - Return code: 0 17:13:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:13:16 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')]} 17:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:16 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')]} 17:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:16 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 0x20b51f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2281a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2427770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', '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 17:13:16 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 17:13:16 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 17:13:16 INFO - Using env: {'DISPLAY': ':0', 17:13:16 INFO - 'HOME': '/home/cltbld', 17:13:16 INFO - 'LANG': 'en_US.UTF-8', 17:13:16 INFO - 'LANGUAGE': 'en_US:en', 17:13:16 INFO - 'LOGNAME': 'cltbld', 17:13:16 INFO - 'MAIL': '/var/mail/cltbld', 17:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:16 INFO - 'MOZ_NO_REMOTE': '1', 17:13:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:16 INFO - 'NO_EM_RESTART': '1', 17:13:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:16 INFO - 'PWD': '/builds/slave/test', 17:13:16 INFO - 'SHELL': '/bin/bash', 17:13:16 INFO - 'SHLVL': '1', 17:13:16 INFO - 'TERM': 'linux', 17:13:16 INFO - 'TMOUT': '86400', 17:13:16 INFO - 'USER': 'cltbld', 17:13:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:16 INFO - '_': '/tools/buildbot/bin/python'} 17:13:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:13:16 INFO - Cleaning up... 17:13:16 INFO - Return code: 0 17:13:16 INFO - Running post-action listener: _resource_record_post_action 17:13:16 INFO - Running post-action listener: _start_resource_monitoring 17:13:16 INFO - Starting resource monitoring. 17:13:16 INFO - [mozharness: 2017-05-12 00:13:16.401707Z] Finished create-virtualenv step (success) 17:13:16 INFO - [mozharness: 2017-05-12 00:13:16.402215Z] Running install step. 17:13:16 INFO - Running pre-action listener: _resource_record_pre_action 17:13:16 INFO - Running main action method: install 17:13:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:13:16 INFO - Reading from file tmpfile_stdout 17:13:16 INFO - Detecting whether we're running mozinstall >=1.0... 17:13:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:13:16 INFO - Reading from file tmpfile_stdout 17:13:16 INFO - Output received: 17:13:16 INFO - Usage: mozinstall [options] installer 17:13:16 INFO - Options: 17:13:16 INFO - -h, --help show this help message and exit 17:13:16 INFO - -d DEST, --destination=DEST 17:13:16 INFO - Directory to install application into. [default: 17:13:16 INFO - "/builds/slave/test"] 17:13:16 INFO - --app=APP Application being installed. [default: firefox] 17:13:16 INFO - mkdir: /builds/slave/test/build/application 17:13:16 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'] 17:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:13:32 INFO - Reading from file tmpfile_stdout 17:13:32 INFO - Output received: 17:13:32 INFO - /builds/slave/test/build/application/firefox/firefox 17:13:32 INFO - Running post-action listener: _resource_record_post_action 17:13:32 INFO - [mozharness: 2017-05-12 00:13:32.480878Z] Finished install step (success) 17:13:32 INFO - [mozharness: 2017-05-12 00:13:32.481127Z] Running run-tests step. 17:13:32 INFO - Running pre-action listener: _resource_record_pre_action 17:13:32 INFO - Running main action method: run_tests 17:13:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:13:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:13:32 INFO - Python 2.7.3 17:13:32 INFO - Return code: 0 17:13:32 INFO - grabbing minidump binary from tooltool 17:13:32 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')]} 17:13:32 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 0x2424a30>, '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 0x24257f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2425ca0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:13:32 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 17:13:32 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 17:13:32 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 17:13:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:13:32 INFO - Return code: 0 17:13:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:13:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:13:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:13:32 INFO - ENV: RUST_BACKTRACE is now 1 17:13:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:13:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:13:32 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/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:13:32 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/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:13:32 INFO - Using env: {'DISPLAY': ':0', 17:13:32 INFO - 'HOME': '/home/cltbld', 17:13:32 INFO - 'LANG': 'en_US.UTF-8', 17:13:32 INFO - 'LANGUAGE': 'en_US:en', 17:13:32 INFO - 'LOGNAME': 'cltbld', 17:13:32 INFO - 'MAIL': '/var/mail/cltbld', 17:13:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:13:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:13:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:32 INFO - 'MOZ_NO_REMOTE': '1', 17:13:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:13:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:32 INFO - 'NO_EM_RESTART': '1', 17:13:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:32 INFO - 'PWD': '/builds/slave/test', 17:13:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:13:32 INFO - 'RUST_BACKTRACE': '1', 17:13:32 INFO - 'SHELL': '/bin/bash', 17:13:32 INFO - 'SHLVL': '1', 17:13:32 INFO - 'TERM': 'linux', 17:13:32 INFO - 'TMOUT': '86400', 17:13:32 INFO - 'USER': 'cltbld', 17:13:32 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470', 17:13:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:32 INFO - '_': '/tools/buildbot/bin/python'} 17:13:32 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/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:13:32 INFO - mozversion application_buildid: 20170511234406 17:13:32 INFO - mozversion application_changeset: c1719e0d676b56ccd940c6f8a9b1bb819d441b20 17:13:32 INFO - mozversion application_display_name: Nightly 17:13:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:13:32 INFO - mozversion application_name: Firefox 17:13:32 INFO - mozversion application_remotingname: firefox 17:13:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:13:32 INFO - mozversion application_vendor: Mozilla 17:13:32 INFO - mozversion application_version: 55.0a1 17:13:32 INFO - mozversion platform_buildid: 20170511234406 17:13:32 INFO - mozversion platform_changeset: c1719e0d676b56ccd940c6f8a9b1bb819d441b20 17:13:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:13:32 INFO - mozversion platform_version: 55.0a1 17:13:32 INFO - using testdate: 1494548012 17:13:32 INFO - actual date: 1494548012 17:13:32 INFO - starting webserver on 'localhost:60450' 17:13:32 INFO - SUITE-START | Running 2 tests 17:13:32 INFO - TEST-START | dromaeo_css 17:13:32 INFO - operating with platform_type : linux_ 17:13:32 INFO - Initialising browser for dromaeo_css test... 17:13:32 INFO - TEST-INFO | started process 7233 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr_khwL/profile http://localhost:60450/getInfo.html) 17:13:36 INFO - PID 7233 | __metrics Screen width/height:1600/1200 17:13:36 INFO - PID 7233 | colorDepth:24 17:13:36 INFO - PID 7233 | Browser inner width/height: 1024/695 17:13:36 INFO - PID 7233 | __metrics 17:13:37 INFO - PID 7233 | 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" 17:13:37 INFO - PID 7233 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:13:38 INFO - TEST-INFO | 7233: exit 0 17:13:38 INFO - Browser initialized. 17:13:38 INFO - Running cycle 1/1 for dromaeo_css test... 17:13:38 INFO - TEST-INFO | started process 7328 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr_khwL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:13:50 INFO - PID 7328 | RSS: Main: 149151744 17:13:50 INFO - PID 7328 | 17:16:56 INFO - PID 7328 | Cycle 1(1): loaded http://localhost:60450/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60450/tests/dromaeo/cssquery-ext.html) 17:16:58 INFO - PID 7328 | RSS: Main: 262320128 17:16:58 INFO - PID 7328 | 17:20:00 INFO - PID 7328 | Cycle 1(1): loaded http://localhost:60450/tests/dromaeo/cssquery-ext.html (next: http://localhost:60450/tests/dromaeo/cssquery-jquery.html) 17:20:01 INFO - PID 7328 | RSS: Main: 220188672 17:20:01 INFO - PID 7328 | 17:23:03 INFO - PID 7328 | Cycle 1(1): loaded http://localhost:60450/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60450/tests/dromaeo/cssquery-mootools.html) 17:23:03 INFO - PID 7328 | RSS: Main: 220483584 17:23:03 INFO - PID 7328 | 17:26:05 INFO - PID 7328 | Cycle 1(1): loaded http://localhost:60450/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60450/tests/dromaeo/cssquery-prototype.html) 17:26:06 INFO - PID 7328 | RSS: Main: 177737728 17:26:06 INFO - PID 7328 | 17:29:09 INFO - PID 7328 | Cycle 1(1): loaded http://localhost:60450/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60450/tests/dromaeo/cssquery-yui.html) 17:29:10 INFO - PID 7328 | RSS: Main: 179806208 17:29:10 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | Cycle 1(1): loaded http://localhost:60450/tests/dromaeo/cssquery-yui.html (next: http://localhost:60450/tests/dromaeo/cssquery-dojo.html) 17:32:12 INFO - PID 7328 | RSS: Main: 270749696 17:32:12 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | __start_tp_report 17:32:12 INFO - PID 7328 | _x_x_mozilla_page_load 17:32:12 INFO - PID 7328 | _x_x_mozilla_page_load_details 17:32:12 INFO - PID 7328 | |i|pagename|runs| 17:32:12 INFO - PID 7328 | |0;dojo.html;2668.6263923050774;2675.1840688589996;2720.782337413007;2725.495783280092;2725.6592925884265;5020.824271150511;5138.177891537355;5231.372235331761;5268.736563171845;5269.530009526832;5184.7148406837605;5211.400688920775;5225.555827754636;5226.05930932432;5241.266222728811;4641.466231383383;4660.464046634646;4662.417197850269;4696.50686677899;4697.718136911796;4623.491415944243;4685.508021657728;4699.318598803172;4716.080364328957;4727.267273572602;4710.882227944312;4727.432708075035;4737.692050016755;4751.928721069186;4800.783964721596;437.23983406648085;4650.4186976627925;4741.596964258042;4752.477227504972;4754.096721622881;4206.72656277341;4611.423572053493;4682.929756053662;4703.647226457995;4707;5213.582913366921;5219.582433405357;5229.633925625208;5231.633785635007;5233.842984710464;5579.358373787026;5691.373948865621;5692.402297758758;5697.943020569783;5728.570357223183;5548.86127846803;5620.606557540974;5629;5633.971830140823;5715.914261286084;5267.078261304257;5268.394134674485;5270.868228294324;5291.074062039166;5302.8939421212135;5728.112142617901;5753.165790960287;5757.798477053283;5765.85585360361;5786.450287222707;5448.210009548593;5515.669059856341;5530.419305972895;5532.28080349552;5580.609357344947;5535.723213839292;5657.519110875622;5705.286839145107;5706.572007099401;5712.685802280831;5485.725713714299;5503.530894708353;5547.278853749068;5573.749181286852;5574.470425309589;5634.85912852182;5635.8591035224445;5657.32112146545;5688.402717714663;5692.459216374438;4597.126545956325;4677.2750223715375;4684.5549672781035;4690.788914498856;4727.976360118246;3965.4448377227213;4015.4177644242004;4016.959830401717;4047.817848196838;4087.734297270668;4799.304100905419;4811.109944660249;4816.831410900601;4824.782884770194;4862.82980095695;5655.095184770417;5657.462541058593;5659.801906933238;5663.745131469093;5665.2635157430095;4725.054989002145;4788.11419887322;4789.401324834395;4791.233402655558;4805.6155507559315;4984.451710311862;5072.340595722606;5091.134507133796;5091.821786237464;5092.236164575269;5308.761105750174;5318.97340513295;5327.200919862052;5327.680339179662;5329.20061990689;5659.292588426447;5707.600467967202;5715.628484148517;5716.79991200306;5753.510951569164;5218.556422704112;5276.5251127398715;5282.81510147143;5285.207218917193;5327.094393953114;5468.644538105011;5519.806806761744;5557.555395568425;5607.607467477237;5652.7173641318595;5691.032524470933;5691.914621280601;5709.9714501427225;5713.9142912855505;5743.827685169452;5454.318210223722;5498.037843377313;5498.835034948958;5505.229267902577;5508.779648814163;5307.840764776908;5406.459354064562;5428.240046393429;5492.615516913777;5556.583256255716;5394.541463975606;5394.919076213781;5414.242006119225;5434.75543600531;5441.673499590037;5526.198701188225;5528.19841123043;5533.418991005887;5534.695591742493;5535.806246781343;5254.632175747814;5384.057789886832;5398.811041613525;5404.675854415694;5447.700376479332 17:32:12 INFO - PID 7328 | |1;ext.html;656.5765081522418;712.1667648850844;732.362844325437;1038.7662775875428;1055.6938487838527;25584.74415255847;32406.513902291455;32698.836505817497;32735.67264327356;32741.50887736683;34719.30561388771;35003.64996350036;35072.2985540289;35110;35193.2961340773;5246.816361427351;6834.589924604515;6976.372126508613;6977.965110174442;6979.930200698004;5321.095413779667;5403.405625381224;5404.891902161954;6346.11154438379;6995.860082798341;7053.294670532957;7091.609961452118;7094.1841688206005;7100.85798284034;7110.644467776617;7001.404880585156;7056.894146587805;7063.470239731989;7066.540674856118;7068.540544864567;5202.947970520284;5328.76020579071;5336.813211537577;6867.519273650846;6942.340477654615;24039;29033.709662903522;29176.27059323534;29197.41605167906;29233.707662923523;2783.77729781619;2815.3243221626867;2818.5772134179833;2820.506411378001;2821.647294088239;23759.287221383387;23802;23833.88083059591;23838.284851454486;23927.28218153458;3133.4203172413177;3166.857491412892;3167.952480712791;3171.0962375722806;3175.2220705926966;140392;140456;140814;141222;141489.99999999898;38860.805695971336;38954.80522597369;39040.414393784115;39137.21725565473;39185.60814391877;41418;41616;41757;41823;41874;12167.574134905413;12236.082293827998;12280.447379867928;12281;12285.01719862409;9927.40435573868;9938.850917236246;9944.850827237597;9947.104760571583;9949.910518791135;10110.24173187014;10340.37957722539;11338.092952563775;11715.121365897592;12127;4845.63657725672;4988.680263677407;4989.525995030539;5056.747162641853;5060.620453466005;871.3987348729484;903.2728653434049;907.8456662367413;915.8900931888217;938.1884669760608;20003.399898003085;20669.17323307051;20839.687404688942;21090.578188436146;21174.894125529583;974.512743628186;974.7952929884804;977.5405559387077;980.5832521178471;999.7050869993369;10605.681829544972;10845.240833141605;11037.889621103846;11148.275362101438;11248;158654;159030;159056;159198;159510;38320.01839760813;38503.229935401134;38515;38583.80708096441;38586;1195.6831439668495;1240.9875901241053;1248.4818800197997;1250.3873102180048;1253.8119282107575;19108.617827643648;19694.90152549228;20159;20225.19099236014;20621.175152993714;29110.12669619915;29137.271568210963;29165;29173;29242.122736317946;144102;144323.2783836116;144595;145251;145358;28349.433011340072;28423.573646395733;28444.715552844205;28459.28851778722;28493;28783.280417989718;29146.417071658023;32613.836930816142;32861.34277314488;32954.5056824143;24917.95139147503;26215.606765898145;26329.868350658126;26570.335741606614;26602.733972660026;25427.237182884543;25669.358266042753;25756.742432575433;27231;27456.45087098287;30513.694863051085;30603.693963060083;31209;31593;31611.84194079015;26878;27280;27388.385380997246;27614.3096422583;28152;21528.461788454788;22242.666360004936;22538.887305563367;22549.774502254768;22753.317400478012 17:32:12 INFO - PID 7328 | |2;jquery.html;377.68085967357575;379.8917308567059;396.8849033780204;403.7153806566371;414;12402.751944961117;13184.20894746316;16555.751663725037;17278.13609319534;17279.654406911854;14107.717845643081;17931.282748690035;18195.818041819577;18396.632067358645;18571.442856714286;5297.602679799012;5504.091824848895;5506.724663766796;5511.944880551203;5513.558915286788;5123.077845987721;5271.973640131794;5297.728545149175;5319.840404787843;5320.122179840322;3293.2919422324194;3456.360573293936;5104.489551044884;5274.525292723654;5386.218998245252;5340.786368545254;5351.839444816662;5359.437259087817;5359.81240656579;5362.919556206641;4742.217534106868;4821.180399332122;4869.732164730938;4877.902441951177;4879.292502587141;21578.460538486506;21586.56826863454;21923.890380548153;22046.88976555123;22218.444538886495;1633.7304344783097;1659.601695593061;1692.5514738594281;1693.3734518228212;1694.7203711387606;14550.345234464474;14604.780928286084;14795.038322509003;14799.926000370036;14804.629884252872;2044.5910817836493;2088.603165398569;2107.157137145154;2112.524681946565;2124.564464284818;68757.65621171912;82430.58784706098;85206;87288.56355718244;89891.55054224687;10597.099246564128;10676.572937082585;10824.13406927444;11138.49876755548;11267.492962816694;14853.2573371331;14862.777058344134;14868.330925108396;14876.70246595062;14903;7323.9633801831715;7472.402207823361;7528.924710752932;7762.417818663623;7787.338076263468;7094.184168820549;7147.070880785569;7200.1719802223415;7356.337929586363;7367.226441223702;6728.461723062143;6840.11078559794;6858.588484690935;6860.897086543706;6893.172819261721;1977.8516611254213;1986.4934441717269;2013.2450331125829;2015.2543558883015;2105.2841933823884;843.9746807595782;872.0581771686564;874.5408660453186;874.6282829797311;902.0753727429437;16746.665066698843;16979.405720799714;17283.481495555152;17287.913560432367;18004.549886252687;876.3252295732378;877.9473231606124;882.9867551986597;903.1600611431431;913.1735779119907;9272.508500132291;9829.361091529028;9863.457509836884;9867.802643947081;9987.201023918069;353036;354732;355611;357801;367559;75411;75788;78030;78634.6068269655;78893.21106788973;1083.274206281793;1088.0914436445657;1127.9436028198722;1137.2778285788418;1139.3904261220207;16547.586310342336;17221.138943052545;17753.378631748343;17778.111094445485;17835.483983861;38177.809110954266;38245.617543824206;38773.41839872461;38910.6108938907;39302.80348598239;48066.75966620145;48293.275600865316;50383.74808125936;50457.48627541179;50884;8019.759407217793;8217.342612591097;8227.794305142419;8357.080721120794;8408.2432581068;12506.624801255977;12831.550895718605;12858.935705321413;13022.479100836239;13216.207027578375;11081.501332439899;11194.384308863091;11442.771144576895;11505.654830354768;11739.471723772622;9995.600175992879;10486.951304869757;10600.681979540626;10892.564297428331;10947.781044379228;10667.146628269875;10747.731306717395;11321.943390282995;11438.885611143782;11850.229735067192;8263.545504997042;8274.58627068632;8381.748547543584;8479.152084791718;8485.066642669179;10823.945880270549;11191.272567283426;11349.262297950607;11408.144389171011;11492.310461372344 17:32:12 INFO - PID 7328 | |3;mootools.html;1241.3793103448274;1290.0840403313646;1293.7994610835312;1302.7264274502347;1304.139268083065;9945;10367.637132700356;10380.48097595121;10381.896181038188;10382.532786024638;10080.294379393445;10108.595656173744;10366.118879895199;10445.582176712922;10449.477526123683;2297.5404919016223;2331.9183828566;2336.8714720690355;2339.3099035784485;2339.508703172336;1961.9117139728746;2060.103854823157;2233.932982010534;2283.5204607032542;2310.976890231093;2165.8267338612873;2243.8990245438913;2260.536589999046;2263.2644390573046;2263.818894488437;2227.2538699535676;2264.1283106004225;2276.1805749930013;2279.464325883416;2280.726312842453;2280.85174463661;2287.7254729432575;2291.4385975435957;2302.804261637763;2305.5734689082574;9931.304808663397;9965.302428829984;10023.949880250624;10032.89967100327;10037.096661300518;5514.228008078872;5680.375158732536;5694.914576281359;5697.886042279172;5698.487136157724;3237.951430728541;3471.1322169457635;3508.0878971467346;3512.7365447591533;3524.8766293179874;1139.4302848575712;1152.487143221267;1166.7608140331213;1177.6937996121071;1179.9174057815956;14970;15082.24588770557;15114.244287785566;15134.848651513454;15164.620884477867;4719.126961512063;4879.707217566886;4977.104121258209;4984.825531106467;4988.925166122511;10789.62236321725;10802.459877006275;10807.78384432307;10809.56761729522;10810.891891081146;5655.915161272585;5676.4323567642905;5679.006173919547;5681.488666020078;5684.431556844283;5096.388433387943;5097.923531147036;5130.076586214517;5163;5181.145111056633;3758.492603498493;3848.191879705293;3851.1527463957887;3874;3900;1017.2319898476751;1048.984265236022;1122.416343501391;1127.4813585750483;1145.8968692817687;578.739567194764;609.5002098279457;683.6445048574714;692.3976140757574;698.7484505577988;5041.168207245763;5130.384353877558;5230.790768369223;5236.842894713165;5390.380106287748;802.2859654907137;804;811.1766556944706;812.273015650989;822.3421262989584;3271.8527666255077;3276.524903888923;3281.753868459875;3297.258116923721;3403.7957722536726;13093.214407135412;13261.071724979352;13461.730765384638;13462.730745385037;13478.663033424047;13358.866411335763;13384.263865487297;13394;13406.396712147976;13406.597802065953;7256.854862902713;7398.667059982314;7450.329470347587;7556.4710470266555;7576.924230757732;5274.7890084396195;5335.11970524859;5440.727963601883;5678.20505129274;5825.300963884361;11461.312321260752;11630.859667889792;11650.359230242419;11946.223495472766;11990.820137698116;11945.940270298594;11950.581729639422;12000.699982500159;12057;12071.39643017863;8047.557384343917;8365.832683346422;8440.957795210985;8457.435374455745;8467.745967620982;9576.712698619052;9664.468454234804;9668.033196680275;9677.322587418814;9680;8205.425620206766;8352.41533092702;8447.873281900653;8535.231829135408;8643.783905402415;7888.723894663659;7978.042634883851;7981.441299109007;8078.353731701567;8111.71608993706;7478.06524184477;7622.961885190539;7623.504472209289;7625.618719063914;7630.542167469969;5436.429174936733;5459.727013649381;5559.249501317432;5604.439556044526;5670.971645141748;5584.134459158837;5622.465865742912;5692.373838877803;5794.420557944172;5812.157237200498 17:32:12 INFO - PID 7328 | |4;prototype.html;384.73453317211124;385.4007019085323;387.9069023434373;388.65603940512636;393.8188433320674;5262.0265250928605;5289.973550132254;5300.522952934235;5321.148616221405;5332.973335133319;5114.386273647158;5165.457626949173;5185.536869986764;5215.478452154783;5233.73831308435;4107.322291821846;4115.012397024722;4121.752694838312;4124.237016152007;4129.958700412987;4116.876493705194;4132.607402296777;4136.193442278757;4137.441445404862;4138.7516748995;3811.7331786774935;4077.673786097105;4107.650849677766;4113.835446582133;4122.50529936408;3941.9014524636827;3948.526176858795;3952.5454572724216;3957;3977.7215594908225;3456.723462123024;3468.531748213984;3478.8782392616135;3490.197254631449;3498.3702933471964;5130.640855140142;5153.742312884378;5154.536091751723;5157.432682404932;5166.715830629314;4568.1548913451115;4635.443746750378;4646.326282689025;4653.953460465385;4660.324252983332;5480.903819236129;5496.972515137439;5516.724163791834;5525.806596769094;5529.364123125812;3865.072382628177;3878.030492376906;3879.9224015519812;3898.2788184186015;3900.1029763154634;5757.4818266355815;5858.677772722499;5869;5895.263092113486;5901.439363260483;3968.3253846846105;3987.142764305688;3987.3819557968563;3990.68074554035;4003.3594624859884;4039.717219794586;4040.3535434330365;4045.3931910213705;4052.1287923096675;4055.3105971984833;4580.862574122782;4644.744539050317;4658.1848176568965;4686.320483529869;4691.413573303337;5593.356763972114;5593.440655934374;5593.664380137205;5594.1608758686525;5609.298837645294;1388.902776805614;1400.9649758756113;1402.3479082226609;1405.6134562995258;1405.6485878530368;940.741296143566;955.5141754571569;1078.7087486378634;1144.9542018319173;1161.7676464707092;4829.589484893753;4836.153673107192;4885.096257192431;4895.290182923456;4909.68087074339;5739.196512488255;5742.59801813869;5753.712314384264;5794.536437085023;5804.65172089676;4802.663813533089;4843;4858.514148585184;4861.854144375674;4894.437139728977;3013.9397212055637;3035.7267845893975;3040.528718048706;3042.710942460463;3061.280599059219;5136;5299.2845965794895;5309.761060752276;5323.7489190040305;5326.174442961347;5633.802816901389;5728.656280623176;5777.682227477445;5827.737751801179;5833.358330583633;5219.233806082782;5325.547328477122;5332.20059468782;5344.118220493575;5347.385050719139;5753.108268218432;5774.249347584787;5775.9133612995;5786.739596718074;5804.94195058044;5585.134304182858;5660.660360378391;5680.4319568042865;5686.800961966312;5711.371749107512;5318.4681531848055;5337.626366154332;5345.545628621455;5347.438518955453;5353.678779273256;4294.613484786384;4404.282329891409;4412.139632771578;4415.483174590453;4415.823367065282;5726.627769195155;5745.511631511368;5762.135679647919;5768.480836724547;5770;5579.972100139474;5586.692731899784;5590.888182236414;5597.160425936142;5599.412061733459;5330.466953304638;5507.614466987273;5537.418571049981;5569.248151499658;5572.916406253828;5468.56251499887;5487.149491828773;5515.7242137893745;5516.282883225155;5522.447755224445;5546.861328466659;5616.157576363578;5650.943490565041;5652.0108980929;5666.744996475251;5456.554013186429;5466.496713403846;5473.370562385217;5482.656749096337;5514.6139770217305 17:32:12 INFO - PID 7328 | |5;yui.html;624.3569123802486;624.6439529468205;712.333967740163;749.2208103572284;769.6651956398966;12975.935120324397;13096.41066152023;13408.195508269511;13418.993575481829;13430.395632196562;13412.932935335333;13705.45178192871;13736.793948090786;13742.862571374284;13743.244121573309;882.3073886998708;887.6449420231894;889.030956257679;889.0398369760643;889.4396530186007;863.3438586674141;888.6445421831255;897.1701176411825;904.1771987491384;906.7914379692675;947.9905200948006;956.5073986896718;956.789506308611;957.1003256938491;962.6197651927484;949.3307218411038;949.7720547068653;950.4344914775767;950.7765675066388;951.1059603972278;694.1011390249588;699.0702365853402;699.8600279944031;700.4466471487518;702.364360253971;13901.652458688513;14009.229492377915;14062.226577538333;14064;14484.855151448455;13771.036027478081;13878.306084695825;14130.576082717433;14143.29283535819;14292.714145717131;12386.256824590553;12629.179103358252;12694.746105077847;12703.364831758468;12733.426995785212;2997.070908018521;3047.512398016329;3048.893288734906;3050.5119180931165;3051.03892273933;68798.65600671964;69745.65127174383;70305;71270.28729712688;71321.64339178323;12141.575044873387;12359.505619775287;12403.25580465175;12456.564020259248;12610.180338278165;14930.40278388874;17043.318267269417;17504.91247543754;21153;21622.783772162078;5351.411344752074;6177.073438984098;6323;6345.619262844244;6357.745690172342;2697.163879197455;2757.2968892932577;2863.55614879694;3026.561148633436;3031.590735250757;3574.5889222739197;3647.1976165243605;3648.616895226016;3668.724845636588;3672.375696131664;746.7834328044806;787.6455594982281;789.7433334166419;790.115071120349;792.2038351456864;582.1384351160307;585.1778251556569;592.1532208941255;605.0289285696482;618.390884978305;9272.953635231781;9558.952205239068;10169.94915025435;10318.329308594917;10811.135109191404;514.5446280042189;515.0343106674984;516.176698166424;516.2488579117376;517.1208944793865;4377.387165796791;5083.847484575394;5219.42586315505;5391.29913111293;5557.194206839986;60563.69718151381;62695.373046269866;63574.68212658999;64773;65974.67012665002;58023.4197658018;58123.4187658118;58303.4169658298;58612.7069364659;58647;49954.75022624864;51807.740961295705;51970.99999999924;52244.21633675498;52820;10566.101881339864;10723.410212438392;10795.838062429228;11074.224804263946;11247.943760281147;26839.463210735285;27090.593641095795;27206.183814485597;27232.591511127746;28630.57054144231;32281.51577726383;32333.35333293368;32627.34745305128;32920.506192407614;33073.33853322872;13980.790288145483;14180.149191048158;14375;14473.203973781543;14498.637534061732;15586.220688965734;15698.215089245721;15932;15937;16209.270582824038;14258.358373873409;14286.9999100062;14300.785488217893;14359.425622974964;14550.636234093809;13999.650008749455;14163.716725665636;14560.271986400849;14639.414423422944;14785.334659940114;14502.85497145015;14588.562343129723;14954.40182392692;15080.54758357251;15151.924240378728;9667.516624168904;9744.12302892743;10190.796184076426;10228.48857557134;10715.035646791826;11782.764344712887;12181.39093045362;12238.816417753562;12287;12484 17:32:12 INFO - PID 7328 | __end_tp_report 17:32:12 INFO - PID 7328 | __start_cc_report 17:32:12 INFO - PID 7328 | _x_x_mozilla_cycle_collect,1382 17:32:12 INFO - PID 7328 | __end_cc_report 17:32:12 INFO - PID 7328 | __startTimestamp1494549132551__endTimestamp 17:32:12 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | ------- Summary: start ------- 17:32:12 INFO - PID 7328 | Number of tests: 6 17:32:12 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | [#0] dojo.html Cycles:165 Average:5136.07 Median:5318.97 stddev:711.72 (13.4%) stddev-sans-first:687.08 17:32:12 INFO - PID 7328 | Values: 2668.6 2675.2 2720.8 2725.5 2725.7 5020.8 5138.2 5231.4 5268.7 5269.5 5184.7 5211.4 5225.6 5226.1 5241.3 4641.5 4660.5 4662.4 4696.5 4697.7 4623.5 4685.5 4699.3 4716.1 4727.3 4710.9 4727.4 4737.7 4751.9 4800.8 437.2 4650.4 4741.6 4752.5 4754.1 4206.7 4611.4 4682.9 4703.6 4707.0 5213.6 5219.6 5229.6 5231.6 5233.8 5579.4 5691.4 5692.4 5697.9 5728.6 5548.9 5620.6 5629.0 5634.0 5715.9 5267.1 5268.4 5270.9 5291.1 5302.9 5728.1 5753.2 5757.8 5765.9 5786.5 5448.2 5515.7 5530.4 5532.3 5580.6 5535.7 5657.5 5705.3 5706.6 5712.7 5485.7 5503.5 5547.3 5573.7 5574.5 5634.9 5635.9 5657.3 5688.4 5692.5 4597.1 4677.3 4684.6 4690.8 4728.0 3965.4 4015.4 4017.0 4047.8 4087.7 4799.3 4811.1 4816.8 4824.8 4862.8 5655.1 5657.5 5659.8 5663.7 5665.3 4725.1 4788.1 4789.4 4791.2 4805.6 4984.5 5072.3 5091.1 5091.8 5092.2 5308.8 5319.0 5327.2 5327.7 5329.2 5659.3 5707.6 5715.6 5716.8 5753.5 5218.6 5276.5 5282.8 5285.2 5327.1 5468.6 5519.8 5557.6 5607.6 5652.7 5691.0 5691.9 5710.0 5713.9 5743.8 5454.3 5498.0 5498.8 5505.2 5508.8 5307.8 5406.5 5428.2 5492.6 5556.6 5394.5 5394.9 5414.2 5434.8 5441.7 5526.2 5528.2 5533.4 5534.7 5535.8 5254.6 5384.1 5398.8 5404.7 5447.7 17:32:12 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | [#1] ext.html Cycles:180 Average:28801.68 Median:29174.64 stddev:38250.61 (131.1%) stddev-sans-first:38299.53 17:32:12 INFO - PID 7328 | Values: 656.6 712.2 732.4 1038.8 1055.7 25584.7 32406.5 32698.8 32735.7 32741.5 34719.3 35003.6 35072.3 35110.0 35193.3 5246.8 6834.6 6976.4 6978.0 6979.9 5321.1 5403.4 5404.9 6346.1 6995.9 7053.3 7091.6 7094.2 7100.9 7110.6 7001.4 7056.9 7063.5 7066.5 7068.5 5202.9 5328.8 5336.8 6867.5 6942.3 24039.0 29033.7 29176.3 29197.4 29233.7 2783.8 2815.3 2818.6 2820.5 2821.6 23759.3 23802.0 23833.9 23838.3 23927.3 3133.4 3166.9 3168.0 3171.1 3175.2 140392.0 140456.0 140814.0 141222.0 141490.0 38860.8 38954.8 39040.4 39137.2 39185.6 41418.0 41616.0 41757.0 41823.0 41874.0 12167.6 12236.1 12280.4 12281.0 12285.0 9927.4 9938.9 9944.9 9947.1 9949.9 10110.2 10340.4 11338.1 11715.1 12127.0 4845.6 4988.7 4989.5 5056.7 5060.6 871.4 903.3 907.8 915.9 938.2 20003.4 20669.2 20839.7 21090.6 21174.9 974.5 974.8 977.5 980.6 999.7 10605.7 10845.2 11037.9 11148.3 11248.0 158654.0 159030.0 159056.0 159198.0 159510.0 38320.0 38503.2 38515.0 38583.8 38586.0 1195.7 1241.0 1248.5 1250.4 1253.8 19108.6 19694.9 20159.0 20225.2 20621.2 29110.1 29137.3 29165.0 29173.0 29242.1 144102.0 144323.3 144595.0 145251.0 145358.0 28349.4 28423.6 28444.7 28459.3 28493.0 28783.3 29146.4 32613.8 32861.3 32954.5 24918.0 26215.6 26329.9 26570.3 26602.7 25427.2 25669.4 25756.7 27231.0 27456.5 30513.7 30603.7 31209.0 31593.0 31611.8 26878.0 27280.0 27388.4 27614.3 28152.0 21528.5 22242.7 22538.9 22549.8 22753.3 17:32:12 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | [#2] jquery.html Cycles:180 Average:24327.33 Median:183968.34 stddev:59543.30 (32.4%) stddev-sans-first:59683.18 17:32:12 INFO - PID 7328 | Values: 377.7 379.9 396.9 403.7 414.0 12402.8 13184.2 16555.8 17278.1 17279.7 14107.7 17931.3 18195.8 18396.6 18571.4 5297.6 5504.1 5506.7 5511.9 5513.6 5123.1 5272.0 5297.7 5319.8 5320.1 3293.3 3456.4 5104.5 5274.5 5386.2 5340.8 5351.8 5359.4 5359.8 5362.9 4742.2 4821.2 4869.7 4877.9 4879.3 21578.5 21586.6 21923.9 22046.9 22218.4 1633.7 1659.6 1692.6 1693.4 1694.7 14550.3 14604.8 14795.0 14799.9 14804.6 2044.6 2088.6 2107.2 2112.5 2124.6 68757.7 82430.6 85206.0 87288.6 89891.6 10597.1 10676.6 10824.1 11138.5 11267.5 14853.3 14862.8 14868.3 14876.7 14903.0 7324.0 7472.4 7528.9 7762.4 7787.3 7094.2 7147.1 7200.2 7356.3 7367.2 6728.5 6840.1 6858.6 6860.9 6893.2 1977.9 1986.5 2013.2 2015.3 2105.3 844.0 872.1 874.5 874.6 902.1 16746.7 16979.4 17283.5 17287.9 18004.5 876.3 877.9 883.0 903.2 913.2 9272.5 9829.4 9863.5 9867.8 9987.2 353036.0 354732.0 355611.0 357801.0 367559.0 75411.0 75788.0 78030.0 78634.6 78893.2 1083.3 1088.1 1127.9 1137.3 1139.4 16547.6 17221.1 17753.4 17778.1 17835.5 38177.8 38245.6 38773.4 38910.6 39302.8 48066.8 48293.3 50383.7 50457.5 50884.0 8019.8 8217.3 8227.8 8357.1 8408.2 12506.6 12831.6 12858.9 13022.5 13216.2 11081.5 11194.4 11442.8 11505.7 11739.5 9995.6 10487.0 10600.7 10892.6 10947.8 10667.1 10747.7 11321.9 11438.9 11850.2 8263.5 8274.6 8381.7 8479.2 8485.1 10823.9 11191.3 11349.3 11408.1 11492.3 17:32:12 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | [#3] mootools.html Cycles:180 Average:6297.33 Median:3518.81 stddev:4038.10 (114.8%) stddev-sans-first:4031.56 17:32:12 INFO - PID 7328 | Values: 1241.4 1290.1 1293.8 1302.7 1304.1 9945.0 10367.6 10380.5 10381.9 10382.5 10080.3 10108.6 10366.1 10445.6 10449.5 2297.5 2331.9 2336.9 2339.3 2339.5 1961.9 2060.1 2233.9 2283.5 2311.0 2165.8 2243.9 2260.5 2263.3 2263.8 2227.3 2264.1 2276.2 2279.5 2280.7 2280.9 2287.7 2291.4 2302.8 2305.6 9931.3 9965.3 10023.9 10032.9 10037.1 5514.2 5680.4 5694.9 5697.9 5698.5 3238.0 3471.1 3508.1 3512.7 3524.9 1139.4 1152.5 1166.8 1177.7 1179.9 14970.0 15082.2 15114.2 15134.8 15164.6 4719.1 4879.7 4977.1 4984.8 4988.9 10789.6 10802.5 10807.8 10809.6 10810.9 5655.9 5676.4 5679.0 5681.5 5684.4 5096.4 5097.9 5130.1 5163.0 5181.1 3758.5 3848.2 3851.2 3874.0 3900.0 1017.2 1049.0 1122.4 1127.5 1145.9 578.7 609.5 683.6 692.4 698.7 5041.2 5130.4 5230.8 5236.8 5390.4 802.3 804.0 811.2 812.3 822.3 3271.9 3276.5 3281.8 3297.3 3403.8 13093.2 13261.1 13461.7 13462.7 13478.7 13358.9 13384.3 13394.0 13406.4 13406.6 7256.9 7398.7 7450.3 7556.5 7576.9 5274.8 5335.1 5440.7 5678.2 5825.3 11461.3 11630.9 11650.4 11946.2 11990.8 11945.9 11950.6 12000.7 12057.0 12071.4 8047.6 8365.8 8441.0 8457.4 8467.7 9576.7 9664.5 9668.0 9677.3 9680.0 8205.4 8352.4 8447.9 8535.2 8643.8 7888.7 7978.0 7981.4 8078.4 8111.7 7478.1 7623.0 7623.5 7625.6 7630.5 5436.4 5459.7 5559.2 5604.4 5671.0 5584.1 5622.5 5692.4 5794.4 5812.2 17:32:12 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | [#4] prototype.html Cycles:180 Average:4608.12 Median:5176.13 stddev:1335.98 (25.8%) stddev-sans-first:1301.58 17:32:12 INFO - PID 7328 | Values: 384.7 385.4 387.9 388.7 393.8 5262.0 5290.0 5300.5 5321.1 5333.0 5114.4 5165.5 5185.5 5215.5 5233.7 4107.3 4115.0 4121.8 4124.2 4130.0 4116.9 4132.6 4136.2 4137.4 4138.8 3811.7 4077.7 4107.7 4113.8 4122.5 3941.9 3948.5 3952.5 3957.0 3977.7 3456.7 3468.5 3478.9 3490.2 3498.4 5130.6 5153.7 5154.5 5157.4 5166.7 4568.2 4635.4 4646.3 4654.0 4660.3 5480.9 5497.0 5516.7 5525.8 5529.4 3865.1 3878.0 3879.9 3898.3 3900.1 5757.5 5858.7 5869.0 5895.3 5901.4 3968.3 3987.1 3987.4 3990.7 4003.4 4039.7 4040.4 4045.4 4052.1 4055.3 4580.9 4644.7 4658.2 4686.3 4691.4 5593.4 5593.4 5593.7 5594.2 5609.3 1388.9 1401.0 1402.3 1405.6 1405.6 940.7 955.5 1078.7 1145.0 1161.8 4829.6 4836.2 4885.1 4895.3 4909.7 5739.2 5742.6 5753.7 5794.5 5804.7 4802.7 4843.0 4858.5 4861.9 4894.4 3013.9 3035.7 3040.5 3042.7 3061.3 5136.0 5299.3 5309.8 5323.7 5326.2 5633.8 5728.7 5777.7 5827.7 5833.4 5219.2 5325.5 5332.2 5344.1 5347.4 5753.1 5774.2 5775.9 5786.7 5804.9 5585.1 5660.7 5680.4 5686.8 5711.4 5318.5 5337.6 5345.5 5347.4 5353.7 4294.6 4404.3 4412.1 4415.5 4415.8 5726.6 5745.5 5762.1 5768.5 5770.0 5580.0 5586.7 5590.9 5597.2 5599.4 5330.5 5507.6 5537.4 5569.2 5572.9 5468.6 5487.1 5515.7 5516.3 5522.4 5546.9 5616.2 5650.9 5652.0 5666.7 5456.6 5466.5 5473.4 5482.7 5514.6 17:32:12 INFO - PID 7328 | 17:32:12 INFO - PID 7328 | [#5] yui.html Cycles:180 Average:15202.78 Median:17666.28 stddev:18032.43 (102.1%) stddev-sans-first:18049.78 17:32:12 INFO - PID 7328 | Values: 624.4 624.6 712.3 749.2 769.7 12975.9 13096.4 13408.2 13419.0 13430.4 13412.9 13705.5 13736.8 13742.9 13743.2 882.3 887.6 889.0 889.0 889.4 863.3 888.6 897.2 904.2 906.8 948.0 956.5 956.8 957.1 962.6 949.3 949.8 950.4 950.8 951.1 694.1 699.1 699.9 700.4 702.4 13901.7 14009.2 14062.2 14064.0 14484.9 13771.0 13878.3 14130.6 14143.3 14292.7 12386.3 12629.2 12694.7 12703.4 12733.4 2997.1 3047.5 3048.9 3050.5 3051.0 68798.7 69745.7 70305.0 71270.3 71321.6 12141.6 12359.5 12403.3 12456.6 12610.2 14930.4 17043.3 17504.9 21153.0 21622.8 5351.4 6177.1 6323.0 6345.6 6357.7 2697.2 2757.3 2863.6 3026.6 3031.6 3574.6 3647.2 3648.6 3668.7 3672.4 746.8 787.6 789.7 790.1 792.2 582.1 585.2 592.2 605.0 618.4 9273.0 9559.0 10169.9 10318.3 10811.1 514.5 515.0 516.2 516.2 517.1 4377.4 5083.8 5219.4 5391.3 5557.2 60563.7 62695.4 63574.7 64773.0 65974.7 58023.4 58123.4 58303.4 58612.7 58647.0 49954.8 51807.7 51971.0 52244.2 52820.0 10566.1 10723.4 10795.8 11074.2 11247.9 26839.5 27090.6 27206.2 27232.6 28630.6 32281.5 32333.4 32627.3 32920.5 33073.3 13980.8 14180.1 14375.0 14473.2 14498.6 15586.2 15698.2 15932.0 15937.0 16209.3 14258.4 14287.0 14300.8 14359.4 14550.6 13999.7 14163.7 14560.3 14639.4 14785.3 14502.9 14588.6 14954.4 15080.5 15151.9 9667.5 9744.1 10190.8 10228.5 10715.0 11782.8 12181.4 12238.8 12287.0 12484.0 17:32:12 INFO - PID 7328 | -------- Summary: end -------- 17:32:12 INFO - PID 7328 | 17:32:13 INFO - TEST-INFO | 7328: exit 0 17:32:13 INFO - TEST-OK | dromaeo_css | took 1120643ms 17:32:13 INFO - TEST-START | kraken 17:32:13 INFO - operating with platform_type : linux_ 17:32:13 INFO - Initialising browser for kraken test... 17:32:13 INFO - TEST-INFO | started process 7656 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEYqGru/profile http://localhost:60450/getInfo.html) 17:32:17 INFO - PID 7656 | __metrics Screen width/height:1600/1200 17:32:17 INFO - PID 7656 | colorDepth:24 17:32:17 INFO - PID 7656 | Browser inner width/height: 1024/695 17:32:17 INFO - PID 7656 | __metrics 17:32:18 INFO - PID 7656 | 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" 17:32:18 INFO - PID 7656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:32:19 INFO - TEST-INFO | 7656: exit 0 17:32:19 INFO - Browser initialized. 17:32:19 INFO - Running cycle 1/1 for kraken test... 17:32:19 INFO - TEST-INFO | started process 7750 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEYqGru/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:32:31 INFO - PID 7750 | RSS: Main: 148676608 17:32:31 INFO - PID 7750 | 17:32:33 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60450/tests/kraken/driver.html?testName=audio-beat-detection) 17:32:34 INFO - PID 7750 | RSS: Main: 220831744 17:32:34 INFO - PID 7750 | 17:32:37 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60450/tests/kraken/driver.html?testName=audio-dft) 17:32:38 INFO - PID 7750 | RSS: Main: 182652928 17:32:38 INFO - PID 7750 | 17:32:41 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60450/tests/kraken/driver.html?testName=audio-fft) 17:32:41 INFO - PID 7750 | RSS: Main: 264491008 17:32:41 INFO - PID 7750 | 17:32:43 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60450/tests/kraken/driver.html?testName=audio-oscillator) 17:32:44 INFO - PID 7750 | RSS: Main: 175300608 17:32:44 INFO - PID 7750 | 17:32:46 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60450/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:32:47 INFO - PID 7750 | RSS: Main: 177946624 17:32:47 INFO - PID 7750 | 17:32:50 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60450/tests/kraken/driver.html?testName=imaging-darkroom) 17:32:50 INFO - PID 7750 | RSS: Main: 237916160 17:32:50 INFO - PID 7750 | 17:32:54 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60450/tests/kraken/driver.html?testName=imaging-desaturate) 17:32:55 INFO - PID 7750 | RSS: Main: 236920832 17:32:55 INFO - PID 7750 | 17:32:57 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60450/tests/kraken/driver.html?testName=json-parse-financial) 17:32:58 INFO - PID 7750 | RSS: Main: 234975232 17:32:58 INFO - PID 7750 | 17:33:00 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60450/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:33:00 INFO - PID 7750 | RSS: Main: 200208384 17:33:00 INFO - PID 7750 | 17:33:02 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60450/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:33:03 INFO - PID 7750 | RSS: Main: 204562432 17:33:03 INFO - PID 7750 | 17:33:05 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60450/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:33:05 INFO - PID 7750 | RSS: Main: 214642688 17:33:05 INFO - PID 7750 | 17:33:08 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60450/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:33:09 INFO - PID 7750 | RSS: Main: 224673792 17:33:09 INFO - PID 7750 | 17:33:12 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60450/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:33:13 INFO - PID 7750 | RSS: Main: 219631616 17:33:13 INFO - PID 7750 | 17:33:14 INFO - PID 7750 | Cycle 1(1): loaded http://localhost:60450/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60450/tests/kraken/driver.html?testName=ai-astar) 17:33:15 INFO - PID 7750 | RSS: Main: 269467648 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | __start_tp_report 17:33:15 INFO - PID 7750 | _x_x_mozilla_page_load 17:33:15 INFO - PID 7750 | _x_x_mozilla_page_load_details 17:33:15 INFO - PID 7750 | |i|pagename|runs| 17:33:15 INFO - PID 7750 | |0;ai-astar;109;112;112;111;110;109;110;111;110;109 17:33:15 INFO - PID 7750 | |1;audio-beat-detection;154;121;124;185;125;123;123;124;149;129 17:33:15 INFO - PID 7750 | |2;audio-dft;163;162;162;160;163;161;162;161;162;162 17:33:15 INFO - PID 7750 | |3;audio-fft;75;76;75;108;75;78;75;76;105;76 17:33:15 INFO - PID 7750 | |4;audio-oscillator;84;83;109;85;83;82;95;82;82;105 17:33:15 INFO - PID 7750 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;114;114;113 17:33:15 INFO - PID 7750 | |6;imaging-darkroom;149;149;150;149;149;149;148;148;149;149 17:33:15 INFO - PID 7750 | |7;imaging-desaturate;94;94;95;94;95;94;94;94;95;93 17:33:15 INFO - PID 7750 | |8;json-parse-financial;54;53;54;52;53;53;53;53;52;53 17:33:15 INFO - PID 7750 | |9;json-stringify-tinderbox;55;56;56;55;55;55;55;56;55;54 17:33:15 INFO - PID 7750 | |10;stanford-crypto-aes;83;81;82;82;84;83;82;90;81;80 17:33:15 INFO - PID 7750 | |11;stanford-crypto-ccm;133;132;131;137;143;130;130;131;191;132 17:33:15 INFO - PID 7750 | |12;stanford-crypto-pbkdf2;183;189;190;194;191;191;185;192;191;191 17:33:15 INFO - PID 7750 | |13;stanford-crypto-sha256-iterative;73;72;76;74;72;75;73;75;71;76 17:33:15 INFO - PID 7750 | __end_tp_report 17:33:15 INFO - PID 7750 | __start_cc_report 17:33:15 INFO - PID 7750 | _x_x_mozilla_cycle_collect,2014 17:33:15 INFO - PID 7750 | __end_cc_report 17:33:15 INFO - PID 7750 | __startTimestamp1494549195163__endTimestamp 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | ------- Summary: start ------- 17:33:15 INFO - PID 7750 | Number of tests: 14 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.50 stddev:1.16 (1.0%) stddev-sans-first:1.13 17:33:15 INFO - PID 7750 | Values: 109.0 112.0 112.0 111.0 110.0 109.0 110.0 111.0 110.0 109.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:127.00 stddev:20.85 (16.4%) stddev-sans-first:21.04 17:33:15 INFO - PID 7750 | Values: 154.0 121.0 124.0 185.0 125.0 123.0 123.0 124.0 149.0 129.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 17:33:15 INFO - PID 7750 | Values: 163.0 162.0 162.0 160.0 163.0 161.0 162.0 161.0 162.0 162.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.50 stddev:13.02 (17.2%) stddev-sans-first:13.56 17:33:15 INFO - PID 7750 | Values: 75.0 76.0 75.0 108.0 75.0 78.0 75.0 76.0 105.0 76.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:83.00 stddev:10.28 (12.4%) stddev-sans-first:10.75 17:33:15 INFO - PID 7750 | Values: 84.0 83.0 109.0 85.0 83.0 82.0 95.0 82.0 82.0 105.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:33:15 INFO - PID 7750 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 114.0 113.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 17:33:15 INFO - PID 7750 | Values: 149.0 149.0 150.0 149.0 149.0 149.0 148.0 148.0 149.0 149.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 17:33:15 INFO - PID 7750 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 94.0 94.0 95.0 93.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.67 (1.3%) stddev-sans-first:0.60 17:33:15 INFO - PID 7750 | Values: 54.0 53.0 54.0 52.0 53.0 53.0 53.0 53.0 52.0 53.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:55.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 17:33:15 INFO - PID 7750 | Values: 55.0 56.0 56.0 55.0 55.0 55.0 55.0 56.0 55.0 54.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:82.50 stddev:2.78 (3.4%) stddev-sans-first:2.95 17:33:15 INFO - PID 7750 | Values: 83.0 81.0 82.0 82.0 84.0 83.0 82.0 90.0 81.0 80.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:132.50 stddev:18.70 (14.1%) stddev-sans-first:19.71 17:33:15 INFO - PID 7750 | Values: 133.0 132.0 131.0 137.0 143.0 130.0 130.0 131.0 191.0 132.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.70 Median:191.00 stddev:3.30 (1.7%) stddev-sans-first:2.46 17:33:15 INFO - PID 7750 | Values: 183.0 189.0 190.0 194.0 191.0 191.0 185.0 192.0 191.0 191.0 17:33:15 INFO - PID 7750 | 17:33:15 INFO - PID 7750 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:74.50 stddev:1.77 (2.4%) stddev-sans-first:1.86 17:33:15 INFO - PID 7750 | Values: 73.0 72.0 76.0 74.0 72.0 75.0 73.0 75.0 71.0 76.0 17:33:15 INFO - PID 7750 | -------- Summary: end -------- 17:33:15 INFO - PID 7750 | 17:33:16 INFO - TEST-INFO | 7750: exit 0 17:33:16 INFO - TEST-OK | kraken | took 62925ms 17:33:16 INFO - SUITE-END | took 1183s 17:33:16 INFO - Completed test suite (00:19:44) 17:33:16 INFO - javascript benchmark 17:33:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6618.3895626149315, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2668.6263923050774, 2675.1840688589996, 2720.782337413007, 2725.495783280092, 2725.6592925884265, 5020.824271150511, 5138.177891537355, 5231.372235331761, 5268.736563171845, 5269.530009526832, 5184.7148406837605, 5211.400688920775, 5225.555827754636, 5226.05930932432, 5241.266222728811, 4641.466231383383, 4660.464046634646, 4662.417197850269, 4696.50686677899, 4697.718136911796, 4623.491415944243, 4685.508021657728, 4699.318598803172, 4716.080364328957, 4727.267273572602, 4710.882227944312, 4727.432708075035, 4737.692050016755, 4751.928721069186, 4800.783964721596, 437.23983406648085, 4650.4186976627925, 4741.596964258042, 4752.477227504972, 4754.096721622881, 4206.72656277341, 4611.423572053493, 4682.929756053662, 4703.647226457995, 4707.0, 5213.582913366921, 5219.582433405357, 5229.633925625208, 5231.633785635007, 5233.842984710464, 5579.358373787026, 5691.373948865621, 5692.402297758758, 5697.943020569783, 5728.570357223183, 5548.86127846803, 5620.606557540974, 5629.0, 5633.971830140823, 5715.914261286084, 5267.078261304257, 5268.394134674485, 5270.868228294324, 5291.074062039166, 5302.8939421212135, 5728.112142617901, 5753.165790960287, 5757.798477053283, 5765.85585360361, 5786.450287222707, 5448.210009548593, 5515.669059856341, 5530.419305972895, 5532.28080349552, 5580.609357344947, 5535.723213839292, 5657.519110875622, 5705.286839145107, 5706.572007099401, 5712.685802280831, 5485.725713714299, 5503.530894708353, 5547.278853749068, 5573.749181286852, 5574.470425309589, 5634.85912852182, 5635.8591035224445, 5657.32112146545, 5688.402717714663, 5692.459216374438, 4597.126545956325, 4677.2750223715375, 4684.5549672781035, 4690.788914498856, 4727.976360118246, 3965.4448377227213, 4015.4177644242004, 4016.959830401717, 4047.817848196838, 4087.734297270668, 4799.304100905419, 4811.109944660249, 4816.831410900601, 4824.782884770194, 4862.82980095695, 5655.095184770417, 5657.462541058593, 5659.801906933238, 5663.745131469093, 5665.2635157430095, 4725.054989002145, 4788.11419887322, 4789.401324834395, 4791.233402655558, 4805.6155507559315, 4984.451710311862, 5072.340595722606, 5091.134507133796, 5091.821786237464, 5092.236164575269, 5308.761105750174, 5318.97340513295, 5327.200919862052, 5327.680339179662, 5329.20061990689, 5659.292588426447, 5707.600467967202, 5715.628484148517, 5716.79991200306, 5753.510951569164, 5218.556422704112, 5276.5251127398715, 5282.81510147143, 5285.207218917193, 5327.094393953114, 5468.644538105011, 5519.806806761744, 5557.555395568425, 5607.607467477237, 5652.7173641318595, 5691.032524470933, 5691.914621280601, 5709.9714501427225, 5713.9142912855505, 5743.827685169452, 5454.318210223722, 5498.037843377313, 5498.835034948958, 5505.229267902577, 5508.779648814163, 5307.840764776908, 5406.459354064562, 5428.240046393429, 5492.615516913777, 5556.583256255716, 5394.541463975606, 5394.919076213781, 5414.242006119225, 5434.75543600531, 5441.673499590037, 5526.198701188225, 5528.19841123043, 5533.418991005887, 5534.695591742493, 5535.806246781343, 5254.632175747814, 5384.057789886832, 5398.811041613525, 5404.675854415694, 5447.700376479332], "value": 5086.325456140442, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.5765081522418, 712.1667648850844, 732.362844325437, 1038.7662775875428, 1055.6938487838527, 25584.74415255847, 32406.513902291455, 32698.836505817497, 32735.67264327356, 32741.50887736683, 34719.30561388771, 35003.64996350036, 35072.2985540289, 35110.0, 35193.2961340773, 5246.816361427351, 6834.589924604515, 6976.372126508613, 6977.965110174442, 6979.930200698004, 5321.095413779667, 5403.405625381224, 5404.891902161954, 6346.11154438379, 6995.860082798341, 7053.294670532957, 7091.609961452118, 7094.1841688206005, 7100.85798284034, 7110.644467776617, 7001.404880585156, 7056.894146587805, 7063.470239731989, 7066.540674856118, 7068.540544864567, 5202.947970520284, 5328.76020579071, 5336.813211537577, 6867.519273650846, 6942.340477654615, 24039.0, 29033.709662903522, 29176.27059323534, 29197.41605167906, 29233.707662923523, 2783.77729781619, 2815.3243221626867, 2818.5772134179833, 2820.506411378001, 2821.647294088239, 23759.287221383387, 23802.0, 23833.88083059591, 23838.284851454486, 23927.28218153458, 3133.4203172413177, 3166.857491412892, 3167.952480712791, 3171.0962375722806, 3175.2220705926966, 140392.0, 140456.0, 140814.0, 141222.0, 141489.99999999898, 38860.805695971336, 38954.80522597369, 39040.414393784115, 39137.21725565473, 39185.60814391877, 41418.0, 41616.0, 41757.0, 41823.0, 41874.0, 12167.574134905413, 12236.082293827998, 12280.447379867928, 12281.0, 12285.01719862409, 9927.40435573868, 9938.850917236246, 9944.850827237597, 9947.104760571583, 9949.910518791135, 10110.24173187014, 10340.37957722539, 11338.092952563775, 11715.121365897592, 12127.0, 4845.63657725672, 4988.680263677407, 4989.525995030539, 5056.747162641853, 5060.620453466005, 871.3987348729484, 903.2728653434049, 907.8456662367413, 915.8900931888217, 938.1884669760608, 20003.399898003085, 20669.17323307051, 20839.687404688942, 21090.578188436146, 21174.894125529583, 974.512743628186, 974.7952929884804, 977.5405559387077, 980.5832521178471, 999.7050869993369, 10605.681829544972, 10845.240833141605, 11037.889621103846, 11148.275362101438, 11248.0, 158654.0, 159030.0, 159056.0, 159198.0, 159510.0, 38320.01839760813, 38503.229935401134, 38515.0, 38583.80708096441, 38586.0, 1195.6831439668495, 1240.9875901241053, 1248.4818800197997, 1250.3873102180048, 1253.8119282107575, 19108.617827643648, 19694.90152549228, 20159.0, 20225.19099236014, 20621.175152993714, 29110.12669619915, 29137.271568210963, 29165.0, 29173.0, 29242.122736317946, 144102.0, 144323.2783836116, 144595.0, 145251.0, 145358.0, 28349.433011340072, 28423.573646395733, 28444.715552844205, 28459.28851778722, 28493.0, 28783.280417989718, 29146.417071658023, 32613.836930816142, 32861.34277314488, 32954.5056824143, 24917.95139147503, 26215.606765898145, 26329.868350658126, 26570.335741606614, 26602.733972660026, 25427.237182884543, 25669.358266042753, 25756.742432575433, 27231.0, 27456.45087098287, 30513.694863051085, 30603.693963060083, 31209.0, 31593.0, 31611.84194079015, 26878.0, 27280.0, 27388.385380997246, 27614.3096422583, 28152.0, 21528.461788454788, 22242.666360004936, 22538.887305563367, 22549.774502254768, 22753.317400478012], "value": 13920.980837878242, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.68085967357575, 379.8917308567059, 396.8849033780204, 403.7153806566371, 414.0, 12402.751944961117, 13184.20894746316, 16555.751663725037, 17278.13609319534, 17279.654406911854, 14107.717845643081, 17931.282748690035, 18195.818041819577, 18396.632067358645, 18571.442856714286, 5297.602679799012, 5504.091824848895, 5506.724663766796, 5511.944880551203, 5513.558915286788, 5123.077845987721, 5271.973640131794, 5297.728545149175, 5319.840404787843, 5320.122179840322, 3293.2919422324194, 3456.360573293936, 5104.489551044884, 5274.525292723654, 5386.218998245252, 5340.786368545254, 5351.839444816662, 5359.437259087817, 5359.81240656579, 5362.919556206641, 4742.217534106868, 4821.180399332122, 4869.732164730938, 4877.902441951177, 4879.292502587141, 21578.460538486506, 21586.56826863454, 21923.890380548153, 22046.88976555123, 22218.444538886495, 1633.7304344783097, 1659.601695593061, 1692.5514738594281, 1693.3734518228212, 1694.7203711387606, 14550.345234464474, 14604.780928286084, 14795.038322509003, 14799.926000370036, 14804.629884252872, 2044.5910817836493, 2088.603165398569, 2107.157137145154, 2112.524681946565, 2124.564464284818, 68757.65621171912, 82430.58784706098, 85206.0, 87288.56355718244, 89891.55054224687, 10597.099246564128, 10676.572937082585, 10824.13406927444, 11138.49876755548, 11267.492962816694, 14853.2573371331, 14862.777058344134, 14868.330925108396, 14876.70246595062, 14903.0, 7323.9633801831715, 7472.402207823361, 7528.924710752932, 7762.417818663623, 7787.338076263468, 7094.184168820549, 7147.070880785569, 7200.1719802223415, 7356.337929586363, 7367.226441223702, 6728.461723062143, 6840.11078559794, 6858.588484690935, 6860.897086543706, 6893.172819261721, 1977.8516611254213, 1986.4934441717269, 2013.2450331125829, 2015.2543558883015, 2105.2841933823884, 843.9746807595782, 872.0581771686564, 874.5408660453186, 874.6282829797311, 902.0753727429437, 16746.665066698843, 16979.405720799714, 17283.481495555152, 17287.913560432367, 18004.549886252687, 876.3252295732378, 877.9473231606124, 882.9867551986597, 903.1600611431431, 913.1735779119907, 9272.508500132291, 9829.361091529028, 9863.457509836884, 9867.802643947081, 9987.201023918069, 353036.0, 354732.0, 355611.0, 357801.0, 367559.0, 75411.0, 75788.0, 78030.0, 78634.6068269655, 78893.21106788973, 1083.274206281793, 1088.0914436445657, 1127.9436028198722, 1137.2778285788418, 1139.3904261220207, 16547.586310342336, 17221.138943052545, 17753.378631748343, 17778.111094445485, 17835.483983861, 38177.809110954266, 38245.617543824206, 38773.41839872461, 38910.6108938907, 39302.80348598239, 48066.75966620145, 48293.275600865316, 50383.74808125936, 50457.48627541179, 50884.0, 8019.759407217793, 8217.342612591097, 8227.794305142419, 8357.080721120794, 8408.2432581068, 12506.624801255977, 12831.550895718605, 12858.935705321413, 13022.479100836239, 13216.207027578375, 11081.501332439899, 11194.384308863091, 11442.771144576895, 11505.654830354768, 11739.471723772622, 9995.600175992879, 10486.951304869757, 10600.681979540626, 10892.564297428331, 10947.781044379228, 10667.146628269875, 10747.731306717395, 11321.943390282995, 11438.885611143782, 11850.229735067192, 8263.545504997042, 8274.58627068632, 8381.748547543584, 8479.152084791718, 8485.066642669179, 10823.945880270549, 11191.272567283426, 11349.262297950607, 11408.144389171011, 11492.310461372344], "value": 8728.594538524323, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1241.3793103448274, 1290.0840403313646, 1293.7994610835312, 1302.7264274502347, 1304.139268083065, 9945.0, 10367.637132700356, 10380.48097595121, 10381.896181038188, 10382.532786024638, 10080.294379393445, 10108.595656173744, 10366.118879895199, 10445.582176712922, 10449.477526123683, 2297.5404919016223, 2331.9183828566, 2336.8714720690355, 2339.3099035784485, 2339.508703172336, 1961.9117139728746, 2060.103854823157, 2233.932982010534, 2283.5204607032542, 2310.976890231093, 2165.8267338612873, 2243.8990245438913, 2260.536589999046, 2263.2644390573046, 2263.818894488437, 2227.2538699535676, 2264.1283106004225, 2276.1805749930013, 2279.464325883416, 2280.726312842453, 2280.85174463661, 2287.7254729432575, 2291.4385975435957, 2302.804261637763, 2305.5734689082574, 9931.304808663397, 9965.302428829984, 10023.949880250624, 10032.89967100327, 10037.096661300518, 5514.228008078872, 5680.375158732536, 5694.914576281359, 5697.886042279172, 5698.487136157724, 3237.951430728541, 3471.1322169457635, 3508.0878971467346, 3512.7365447591533, 3524.8766293179874, 1139.4302848575712, 1152.487143221267, 1166.7608140331213, 1177.6937996121071, 1179.9174057815956, 14970.0, 15082.24588770557, 15114.244287785566, 15134.848651513454, 15164.620884477867, 4719.126961512063, 4879.707217566886, 4977.104121258209, 4984.825531106467, 4988.925166122511, 10789.62236321725, 10802.459877006275, 10807.78384432307, 10809.56761729522, 10810.891891081146, 5655.915161272585, 5676.4323567642905, 5679.006173919547, 5681.488666020078, 5684.431556844283, 5096.388433387943, 5097.923531147036, 5130.076586214517, 5163.0, 5181.145111056633, 3758.492603498493, 3848.191879705293, 3851.1527463957887, 3874.0, 3900.0, 1017.2319898476751, 1048.984265236022, 1122.416343501391, 1127.4813585750483, 1145.8968692817687, 578.739567194764, 609.5002098279457, 683.6445048574714, 692.3976140757574, 698.7484505577988, 5041.168207245763, 5130.384353877558, 5230.790768369223, 5236.842894713165, 5390.380106287748, 802.2859654907137, 804.0, 811.1766556944706, 812.273015650989, 822.3421262989584, 3271.8527666255077, 3276.524903888923, 3281.753868459875, 3297.258116923721, 3403.7957722536726, 13093.214407135412, 13261.071724979352, 13461.730765384638, 13462.730745385037, 13478.663033424047, 13358.866411335763, 13384.263865487297, 13394.0, 13406.396712147976, 13406.597802065953, 7256.854862902713, 7398.667059982314, 7450.329470347587, 7556.4710470266555, 7576.924230757732, 5274.7890084396195, 5335.11970524859, 5440.727963601883, 5678.20505129274, 5825.300963884361, 11461.312321260752, 11630.859667889792, 11650.359230242419, 11946.223495472766, 11990.820137698116, 11945.940270298594, 11950.581729639422, 12000.699982500159, 12057.0, 12071.39643017863, 8047.557384343917, 8365.832683346422, 8440.957795210985, 8457.435374455745, 8467.745967620982, 9576.712698619052, 9664.468454234804, 9668.033196680275, 9677.322587418814, 9680.0, 8205.425620206766, 8352.41533092702, 8447.873281900653, 8535.231829135408, 8643.783905402415, 7888.723894663659, 7978.042634883851, 7981.441299109007, 8078.353731701567, 8111.71608993706, 7478.06524184477, 7622.961885190539, 7623.504472209289, 7625.618719063914, 7630.542167469969, 5436.429174936733, 5459.727013649381, 5559.249501317432, 5604.439556044526, 5670.971645141748, 5584.134459158837, 5622.465865742912, 5692.373838877803, 5794.420557944172, 5812.157237200498], "value": 4744.666537843189, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.73453317211124, 385.4007019085323, 387.9069023434373, 388.65603940512636, 393.8188433320674, 5262.0265250928605, 5289.973550132254, 5300.522952934235, 5321.148616221405, 5332.973335133319, 5114.386273647158, 5165.457626949173, 5185.536869986764, 5215.478452154783, 5233.73831308435, 4107.322291821846, 4115.012397024722, 4121.752694838312, 4124.237016152007, 4129.958700412987, 4116.876493705194, 4132.607402296777, 4136.193442278757, 4137.441445404862, 4138.7516748995, 3811.7331786774935, 4077.673786097105, 4107.650849677766, 4113.835446582133, 4122.50529936408, 3941.9014524636827, 3948.526176858795, 3952.5454572724216, 3957.0, 3977.7215594908225, 3456.723462123024, 3468.531748213984, 3478.8782392616135, 3490.197254631449, 3498.3702933471964, 5130.640855140142, 5153.742312884378, 5154.536091751723, 5157.432682404932, 5166.715830629314, 4568.1548913451115, 4635.443746750378, 4646.326282689025, 4653.953460465385, 4660.324252983332, 5480.903819236129, 5496.972515137439, 5516.724163791834, 5525.806596769094, 5529.364123125812, 3865.072382628177, 3878.030492376906, 3879.9224015519812, 3898.2788184186015, 3900.1029763154634, 5757.4818266355815, 5858.677772722499, 5869.0, 5895.263092113486, 5901.439363260483, 3968.3253846846105, 3987.142764305688, 3987.3819557968563, 3990.68074554035, 4003.3594624859884, 4039.717219794586, 4040.3535434330365, 4045.3931910213705, 4052.1287923096675, 4055.3105971984833, 4580.862574122782, 4644.744539050317, 4658.1848176568965, 4686.320483529869, 4691.413573303337, 5593.356763972114, 5593.440655934374, 5593.664380137205, 5594.1608758686525, 5609.298837645294, 1388.902776805614, 1400.9649758756113, 1402.3479082226609, 1405.6134562995258, 1405.6485878530368, 940.741296143566, 955.5141754571569, 1078.7087486378634, 1144.9542018319173, 1161.7676464707092, 4829.589484893753, 4836.153673107192, 4885.096257192431, 4895.290182923456, 4909.68087074339, 5739.196512488255, 5742.59801813869, 5753.712314384264, 5794.536437085023, 5804.65172089676, 4802.663813533089, 4843.0, 4858.514148585184, 4861.854144375674, 4894.437139728977, 3013.9397212055637, 3035.7267845893975, 3040.528718048706, 3042.710942460463, 3061.280599059219, 5136.0, 5299.2845965794895, 5309.761060752276, 5323.7489190040305, 5326.174442961347, 5633.802816901389, 5728.656280623176, 5777.682227477445, 5827.737751801179, 5833.358330583633, 5219.233806082782, 5325.547328477122, 5332.20059468782, 5344.118220493575, 5347.385050719139, 5753.108268218432, 5774.249347584787, 5775.9133612995, 5786.739596718074, 5804.94195058044, 5585.134304182858, 5660.660360378391, 5680.4319568042865, 5686.800961966312, 5711.371749107512, 5318.4681531848055, 5337.626366154332, 5345.545628621455, 5347.438518955453, 5353.678779273256, 4294.613484786384, 4404.282329891409, 4412.139632771578, 4415.483174590453, 4415.823367065282, 5726.627769195155, 5745.511631511368, 5762.135679647919, 5768.480836724547, 5770.0, 5579.972100139474, 5586.692731899784, 5590.888182236414, 5597.160425936142, 5599.412061733459, 5330.466953304638, 5507.614466987273, 5537.418571049981, 5569.248151499658, 5572.916406253828, 5468.56251499887, 5487.149491828773, 5515.7242137893745, 5516.282883225155, 5522.447755224445, 5546.861328466659, 5616.157576363578, 5650.943490565041, 5652.0108980929, 5666.744996475251, 5456.554013186429, 5466.496713403846, 5473.370562385217, 5482.656749096337, 5514.6139770217305], "value": 4207.101074837044, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.3569123802486, 624.6439529468205, 712.333967740163, 749.2208103572284, 769.6651956398966, 12975.935120324397, 13096.41066152023, 13408.195508269511, 13418.993575481829, 13430.395632196562, 13412.932935335333, 13705.45178192871, 13736.793948090786, 13742.862571374284, 13743.244121573309, 882.3073886998708, 887.6449420231894, 889.030956257679, 889.0398369760643, 889.4396530186007, 863.3438586674141, 888.6445421831255, 897.1701176411825, 904.1771987491384, 906.7914379692675, 947.9905200948006, 956.5073986896718, 956.789506308611, 957.1003256938491, 962.6197651927484, 949.3307218411038, 949.7720547068653, 950.4344914775767, 950.7765675066388, 951.1059603972278, 694.1011390249588, 699.0702365853402, 699.8600279944031, 700.4466471487518, 702.364360253971, 13901.652458688513, 14009.229492377915, 14062.226577538333, 14064.0, 14484.855151448455, 13771.036027478081, 13878.306084695825, 14130.576082717433, 14143.29283535819, 14292.714145717131, 12386.256824590553, 12629.179103358252, 12694.746105077847, 12703.364831758468, 12733.426995785212, 2997.070908018521, 3047.512398016329, 3048.893288734906, 3050.5119180931165, 3051.03892273933, 68798.65600671964, 69745.65127174383, 70305.0, 71270.28729712688, 71321.64339178323, 12141.575044873387, 12359.505619775287, 12403.25580465175, 12456.564020259248, 12610.180338278165, 14930.40278388874, 17043.318267269417, 17504.91247543754, 21153.0, 21622.783772162078, 5351.411344752074, 6177.073438984098, 6323.0, 6345.619262844244, 6357.745690172342, 2697.163879197455, 2757.2968892932577, 2863.55614879694, 3026.561148633436, 3031.590735250757, 3574.5889222739197, 3647.1976165243605, 3648.616895226016, 3668.724845636588, 3672.375696131664, 746.7834328044806, 787.6455594982281, 789.7433334166419, 790.115071120349, 792.2038351456864, 582.1384351160307, 585.1778251556569, 592.1532208941255, 605.0289285696482, 618.390884978305, 9272.953635231781, 9558.952205239068, 10169.94915025435, 10318.329308594917, 10811.135109191404, 514.5446280042189, 515.0343106674984, 516.176698166424, 516.2488579117376, 517.1208944793865, 4377.387165796791, 5083.847484575394, 5219.42586315505, 5391.29913111293, 5557.194206839986, 60563.69718151381, 62695.373046269866, 63574.68212658999, 64773.0, 65974.67012665002, 58023.4197658018, 58123.4187658118, 58303.4169658298, 58612.7069364659, 58647.0, 49954.75022624864, 51807.740961295705, 51970.99999999924, 52244.21633675498, 52820.0, 10566.101881339864, 10723.410212438392, 10795.838062429228, 11074.224804263946, 11247.943760281147, 26839.463210735285, 27090.593641095795, 27206.183814485597, 27232.591511127746, 28630.57054144231, 32281.51577726383, 32333.35333293368, 32627.34745305128, 32920.506192407614, 33073.33853322872, 13980.790288145483, 14180.149191048158, 14375.0, 14473.203973781543, 14498.637534061732, 15586.220688965734, 15698.215089245721, 15932.0, 15937.0, 16209.270582824038, 14258.358373873409, 14286.9999100062, 14300.785488217893, 14359.425622974964, 14550.636234093809, 13999.650008749455, 14163.716725665636, 14560.271986400849, 14639.414423422944, 14785.334659940114, 14502.85497145015, 14588.562343129723, 14954.40182392692, 15080.54758357251, 15151.924240378728, 9667.516624168904, 9744.12302892743, 10190.796184076426, 10228.48857557134, 10715.035646791826, 11782.764344712887, 12181.39093045362, 12238.816417753562, 12287.0, 12484.0], "value": 6812.01958679487, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1528.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 112.0, 111.0, 110.0, 109.0, 110.0, 111.0, 110.0, 109.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 124.0, 185.0, 125.0, 123.0, 123.0, 124.0, 149.0, 129.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 162.0, 160.0, 163.0, 161.0, 162.0, 161.0, 162.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 108.0, 75.0, 78.0, 75.0, 76.0, 105.0, 76.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 109.0, 85.0, 83.0, 82.0, 95.0, 82.0, 82.0, 105.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 150.0, 149.0, 149.0, 149.0, 148.0, 148.0, 149.0, 149.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 52.0, 53.0, 53.0, 53.0, 53.0, 52.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 55.0, 55.0, 55.0, 55.0, 56.0, 55.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 82.0, 82.0, 84.0, 83.0, 82.0, 90.0, 81.0, 80.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 132.0, 131.0, 137.0, 143.0, 130.0, 130.0, 131.0, 191.0, 132.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 189.0, 190.0, 194.0, 191.0, 191.0, 185.0, 192.0, 191.0, 191.0], "value": 189.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 76.0, 74.0, 72.0, 75.0, 73.0, 75.0, 71.0, 76.0], "value": 73.7, "unit": "score"}]}]} 17:33:16 INFO - Return code: 0 17:33:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:33:16 INFO - # TBPL SUCCESS # 17:33:16 INFO - Running post-action listener: _resource_record_post_action 17:33:16 INFO - [mozharness: 2017-05-12 00:33:16.978241Z] Finished run-tests step (success) 17:33:16 INFO - Running post-run listener: _resource_record_post_run 17:33:17 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 359669760; Read time: 8; Write time: 228660 17:33:17 INFO - TinderboxPrint: CPU usage
12.9% 17:33:17 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:33:17 INFO - TinderboxPrint: I/O write bytes / time
359,669,760 / 228,660 17:33:17 INFO - TinderboxPrint: CPU idle
8,339.6 (87.1%) 17:33:17 INFO - TinderboxPrint: CPU user
1,196.1 (12.5%) 17:33:17 INFO - TinderboxPrint: Swap in / out
0 / 0 17:33:17 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 102096896; Read time: 0; Write time: 100200 17:33:17 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 256417792; Read time: 0; Write time: 128428 17:33:17 INFO - Running post-run listener: _upload_blobber_files 17:33:17 INFO - Blob upload gear active. 17:33:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:33:17 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 17:33:17 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'] 17:33:17 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 17:33:18 INFO - (blobuploader) - INFO - Open directory for files ... 17:33:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:33:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:33:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:18 INFO - (blobuploader) - INFO - Done attempting. 17:33:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:33:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:33:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:19 INFO - (blobuploader) - INFO - Done attempting. 17:33:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:33:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:33:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:20 INFO - (blobuploader) - INFO - Done attempting. 17:33:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:33:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:33:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:23 INFO - (blobuploader) - INFO - Done attempting. 17:33:23 INFO - (blobuploader) - INFO - Iteration through files over. 17:33:23 INFO - Return code: 0 17:33:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:33:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:33:23 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2fc0d2afebc4d569f247396f1cf648cdea81816ad671be84e9c42a276943ba7f1da722090a48d486ece7857738c756307d59b1a9762b35415f6660fff86a199", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a3a9ddefa1fe34102f97b9d8dc46ca07c3a143304f525615c357e93050f8145b34a02cff07f2e151413cf9550bd513bfdbad5b5ef38fdf6e44c3f1d6fbb7d46b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fe07af832386c0bc74fd1f4f709002e4b6d8f7ecb7e80c5b8d29efd96387ff35eb92d55b6a02de989076e53675321344855aae83a450c6e67586a80bd5833c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/72b8ebed4dd9a87e895df553a36c9a9ff92fb7e3b3cdec3c0b985576fe363521a85d305a4141135537b1be268e5c7d1a856a5ef3907b8f9b9a1b9c3d04a6976d"} 17:33:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:33:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:33:23 INFO - Contents: 17:33:23 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2fc0d2afebc4d569f247396f1cf648cdea81816ad671be84e9c42a276943ba7f1da722090a48d486ece7857738c756307d59b1a9762b35415f6660fff86a199", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a3a9ddefa1fe34102f97b9d8dc46ca07c3a143304f525615c357e93050f8145b34a02cff07f2e151413cf9550bd513bfdbad5b5ef38fdf6e44c3f1d6fbb7d46b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fe07af832386c0bc74fd1f4f709002e4b6d8f7ecb7e80c5b8d29efd96387ff35eb92d55b6a02de989076e53675321344855aae83a450c6e67586a80bd5833c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/72b8ebed4dd9a87e895df553a36c9a9ff92fb7e3b3cdec3c0b985576fe363521a85d305a4141135537b1be268e5c7d1a856a5ef3907b8f9b9a1b9c3d04a6976d"} 17:33:23 INFO - Running post-run listener: copy_logs_to_upload_dir 17:33:23 INFO - Copying logs to upload dir... 17:33:23 INFO - mkdir: /builds/slave/test/build/upload/logs 17:33:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.074319 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-05-11 17:33:23.197906) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 17:33:23.202671) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2fc0d2afebc4d569f247396f1cf648cdea81816ad671be84e9c42a276943ba7f1da722090a48d486ece7857738c756307d59b1a9762b35415f6660fff86a199", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a3a9ddefa1fe34102f97b9d8dc46ca07c3a143304f525615c357e93050f8145b34a02cff07f2e151413cf9550bd513bfdbad5b5ef38fdf6e44c3f1d6fbb7d46b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fe07af832386c0bc74fd1f4f709002e4b6d8f7ecb7e80c5b8d29efd96387ff35eb92d55b6a02de989076e53675321344855aae83a450c6e67586a80bd5833c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/72b8ebed4dd9a87e895df553a36c9a9ff92fb7e3b3cdec3c0b985576fe363521a85d305a4141135537b1be268e5c7d1a856a5ef3907b8f9b9a1b9c3d04a6976d"} build_url:https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011283 build_url: 'https://queue.taskcluster.net/v1/task/ZUPAr4LpSymjIB81NaP2cA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a2fc0d2afebc4d569f247396f1cf648cdea81816ad671be84e9c42a276943ba7f1da722090a48d486ece7857738c756307d59b1a9762b35415f6660fff86a199", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a3a9ddefa1fe34102f97b9d8dc46ca07c3a143304f525615c357e93050f8145b34a02cff07f2e151413cf9550bd513bfdbad5b5ef38fdf6e44c3f1d6fbb7d46b", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fe07af832386c0bc74fd1f4f709002e4b6d8f7ecb7e80c5b8d29efd96387ff35eb92d55b6a02de989076e53675321344855aae83a450c6e67586a80bd5833c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/72b8ebed4dd9a87e895df553a36c9a9ff92fb7e3b3cdec3c0b985576fe363521a85d305a4141135537b1be268e5c7d1a856a5ef3907b8f9b9a1b9c3d04a6976d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 17:33:23.230310) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:33:23.230624) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=c6927cf182745ad25b1d38b9000001fe-1494547948.136513-1367700470 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004445 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:33:23.311158) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 17:33:23.311483) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 17:33:23.311792) ========= ========= Total master_lag: 0.18 =========